D [26/Jan/2016:11:42:53 +0100] [Job 5375616] time-at-processing=1453804973
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] 3 filters for job:
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] pstopdf (application/postscript to application/pdf, cost 0)
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] foomatic-rip (application/vnd.cups-pdf to printer/p077_09, cost 0)
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] job-sheets=none,none
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] argv[0]="p077_09"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] argv[1]="5375616"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] argv[2]="PIREMOTE"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] argv[3]="002eyejR.DRP"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] argv[4]="1"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] argv[5]="document-name=002eyejR.DRP job-originating-host-name=dosap61.company.com job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c date-time-at-creation= date-time-at-processing= time-at-creation=1453727301 time-at-processing=1453804973 document-name-supplied= document-name-supplied= document-name-supplied="
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] argv[6]="/var/spool/cups/d5375616-001"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[7]="CUPS_STATEDIR=/run/cups"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[8]="HOME=/var/spool/cups/tmp"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[10]="SERVER_ADMIN=root@plato"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[11]="SOFTWARE=CUPS/1.7.5"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[13]="USER=root"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[14]="CUPS_MAX_MESSAGE=2047"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[17]="IPP_PORT=631"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[18]="CHARSET=utf-8"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[19]="LANG=en.UTF-8"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[20]="PPD=/etc/cups/ppd/p077_09.ppd"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[21]="RIP_MAX_CACHE=128m"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[22]="CONTENT_TYPE=application/postscript"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[23]="DEVICE_URI=socket://prt09.hit077.company.com:9100/?snmp=false"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[24]="PRINTER_INFO=Drucker zum Verschieben"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[25]="PRINTER_LOCATION="
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[26]="PRINTER=p077_09"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[27]="PRINTER_STATE_REASONS=none"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[28]="CUPS_FILETYPE=document"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] envp[30]="AUTH_I****"
I [26/Jan/2016:11:42:53 +0100] [Job 5375616] Started filter /usr/lib/cups/filter/pstopdf (PID 16504)
I [26/Jan/2016:11:42:53 +0100] [Job 5375616] Started filter /usr/lib/cups/filter/pdftopdf (PID 16505)
I [26/Jan/2016:11:42:53 +0100] [Job 5375616] Started filter /usr/lib/cups/filter/foomatic-rip (PID 16510)
I [26/Jan/2016:11:42:53 +0100] [Job 5375616] Started backend /usr/lib/cups/backend/socket (PID 16511)
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] pstopdf 6 args: 5375616 PIREMOTE 002eyejR.DRP 1 document-name=002eyejR.DRP job-originating-host-name=dosap61.company.com job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c date-time-at-creation= date-time-at-processing= time-at-creation=1453727301 time-at-processing=1453804973 document-name-supplied= document-name-supplied= document-name-supplied= /var/spool/cups/d5375616-001
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] PPD: /etc/cups/ppd/p077_09.ppd
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Calling FindDeviceById(cups-p077_09)
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Failed to get find device cups-p077_09
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] 'CM Color Calibration' Mode in SPOOLER-LESS: Off
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Getting input from file 
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] foomatic-rip version 1.0.58 running...
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Parsing PPD file ...
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option OptCST
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] STATE: +connecting-to-device
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Looking up "prt09.hit077.company.com"...
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] backendWaitLoop(snmp_fd=-1, addr=0x7f86f5c78c38, side_cb=0x7f86f585e4b0)
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option ColorSpace
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option InputSlot
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option MediaType
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option Resolution
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option Duplex
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option BindingLocation
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option Gutter
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option CN_DPI
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option ImageRefine
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option Gscale
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option DotGAdjust
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option TonerSave
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option HalfTone
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option HalftoneType
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option ScreenProc
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option Transfer
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option LeadingEdge
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option PageSize
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option ImageableArea
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option PaperDimension
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option Font
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Added option ColorSep
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Parameter Summary
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] -----------------
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Spooler: cups
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Printer: p077_09
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Shell: /bin/bash
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] PPD file: /etc/cups/ppd/p077_09.ppd
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] ATTR file: 
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Printer model: Canon LBP6680/3480 PS
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Job title: 002eyejR.DRP
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] File(s) to be printed:
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] <STDIN>
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Printing system options:
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'document-name=002eyejR.DRP'
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option document-name=002eyejR.DRP.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'job-originating-host-name=dosap61.company.com'
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option job-originating-host-name=dosap61.company.com.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c'
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'date-time-at-creation='
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option date-time-at-creation=.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'date-time-at-processing='
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option date-time-at-processing=.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'time-at-creation=1453727301'
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option time-at-creation=1453727301.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'time-at-processing=1453804973'
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option time-at-processing=1453804973.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'document-name-supplied='
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option document-name-supplied=.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'document-name-supplied='
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option document-name-supplied=.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Pondering option 'document-name-supplied='
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Unknown option document-name-supplied=.
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] CM Color Calibration Mode in CUPS: Off
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Options from the PPD file:
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] ================================================
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] File: <STDIN>
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] ================================================
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Resolution: 600
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Orientation requested: 0
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Page size: A4
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Width: 595, height: 842, absolute margins: 11.3386, 11.3386, 583.6614, 830.6614
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Relative margins: 11.3386, 11.3386, 11.3386, 11.3386
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] PPD options: -r600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] PostScript to be injected: <</.HWMargins[11.3386 11.3386 11.3386 11.3386] /Margins[0 0]>>setpagedevice
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -dNOINTERPOLATE -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer -dUseCIEColor                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=-  -c <</.HWMargins[11.3386 11.3386 11.3386 11.3386] /Margins[0 0]>>setpagedevice .setpdfwrite -f -
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] GPL Ghostscript 9.15: 
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Use of -dUseCIEColor detected!
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] Since the release of version 9.11 of Ghostscript we recommend you do not set
D [26/Jan/2016:11:42:53 +0100] [Job 5375616] -dUseCIEColor with the pdfwrite/ps2write device family.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] GPL Ghostscript 9.15: 
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Use of -dUseCIEColor detected!
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Since the release of version 9.11 of Ghostscript we recommend you do not set
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] -dUseCIEColor with the pdfwrite/ps2write device family.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] GPL Ghostscript 9.15: 
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Use of -dUseCIEColor detected!
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Since the release of version 9.11 of Ghostscript we recommend you do not set
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] -dUseCIEColor with the pdfwrite/ps2write device family.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PID 16504 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Filetype: PDF
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PostScript option found: InputSlot=Auto: "<</InputAttributes <</Priority []>> >>
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] systemdict /setpagedevice get exec"
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Driver does not understand PDF input, converting to PostScript
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Storing temporary files in /var/spool/cups/tmp
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Starting process "pdf-to-ps" (generation 1)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PID 16505 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Printer make and model: Canon LBP6680/3480
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Running command line for pstops: pstops 5375616 PIREMOTE 002eyejR.DRP 1 ' document-name=002eyejR.DRP job-originating-host-name=dosap61.company.com job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c date-time-at-creation= date-time-at-processing= time-at-creation=1453727301 time-at-processing=1453804973 document-name-supplied= document-name-supplied= document-name-supplied='
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Using image rendering resolution 600 dpi
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Running command line for pdftops: pdftops -level3 -origpagesizes -nocenter -r 600 /var/spool/cups/tmp/foomatic-TjQPU8 -
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Started filter pdftops (PID 16557)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Started filter pstops (PID 16558)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Page = 595x842; 11,11 to 584,831
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] slow_collate=0, slow_duplex=0, slow_order=0
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Before copy_comments - %!PS-Adobe-3.0
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %!PS-Adobe-3.0
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %Produced by poppler pdftops version: 0.24.4 (http://poppler.freedesktop.org)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %%LanguageLevel: 3
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %%DocumentSuppliedResources: (atend)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %%DocumentMedia: 595x842 595 842 0 () ()
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %%BoundingBox: 0 0 595 842
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %%Pages: 2
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] %%EndComments
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Before copy_prolog - %%BeginProlog
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Filetype: PostScript
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Reading PostScript input ...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> This document is DSC-conforming!
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found %RBINumCopies: 1
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginProlog
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Inserting option code into "Prolog" section.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Before copy_setup - %%BeginSetup
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%EndProlog
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginSetup
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *OptCST False
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: OptCST=False
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *InputSlot Auto
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: InputSlot=Auto
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *Duplex None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: Duplex=None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *Gscale Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: Gscale=Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *Gutter None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: Gutter=None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *HalfTone Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: HalfTone=Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *ImageRefine Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: ImageRefine=Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *BindingLocation None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: BindingLocation=None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *MediaType None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: MediaType=None
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *DotGAdjust Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: DotGAdjust=Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *PageRegion A4
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: PageRegion=A4
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *TonerSave Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: TonerSave=Default
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginFeature: *CN_DPI 600dpi
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Option: CN_DPI=600dpi
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Before page loop - %%Page: 1 1
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Copying page 1...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] pagew = 572.3, pagel = 819.3
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PageLeft = 11.3, PageRight = 583.7
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PageTop = 830.7, PageBottom = 11.3
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PageWidth = 595.0, PageLength = 842.0
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%EndSetup
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Inserting PostScript code for CUPS' page accounting
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Inserting option code into "Setup" section.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] New page: %%Page: 1 1
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginPageSetup
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Inserting option code into "PageSetup" section.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Copying page 2...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] pagew = 572.3, pagel = 819.3
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PageLeft = 11.3, PageRight = 583.7
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PageTop = 830.7, PageBottom = 11.3
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PageWidth = 595.0, PageLength = 842.0
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] New page found but previous not printed, print it now.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%Page: 2 2
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Output goes directly to the renderer now.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PID 16557 (pdftops) exited with no errors.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 2 pages...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] PID 16558 (pstops) exited with no errors.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Starting renderer with command: "/bin/cat - | sicgsfilter -MPS -NP  -uPIREMOTE -V"002eyejR.DRP" -n1 "
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Starting process "kid3" (generation 1)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Starting process "kid4" (generation 2)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Starting process "renderer" (generation 2)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] JCL: %-12345X@PJL
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] <job data> 
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] New page: %%Page: 2 2
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Connecting to prt09.hit077.company.com:9100
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%Page: 2 2
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] --> Output goes to the FIFO buffer now.
I [26/Jan/2016:11:42:54 +0100] [Job 5375616] Connecting to printer.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Set job-printer-state-message to "Connecting to printer.", current level=INFO
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Found: %%BeginPageSetup
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Inserting option code into "PageSetup" section.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Flushing FIFO.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Closing renderer
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] STATE: -connecting-to-device
I [26/Jan/2016:11:42:54 +0100] [Job 5375616] Connected to printer.
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Set job-printer-state-message to "Connected to printer.", current level=INFO
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Connected to 10.124.152.44:9100...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] backendRunLoop(print_fd=0, device_fd=5, snmp_fd=-1, addr=0x7f86f5c78c38, use_bc=1, side_cb=0x7f86f585e4b0)
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] renderer exited with status 0
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Wrote 8192 bytes of print data...
D [26/Jan/2016:11:42:54 +0100] [Job 5375616] Read 8192 bytes of print data...
I [26/Jan/2016:11:45:02 +0100] [Job 5375616] Job stopped due to printer being paused.
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Process is dying with "Caught termination signal: Job canceled
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] ", exit stat 0
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Cleaning up...
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Process is dying with "Caught termination signal: Job canceled
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] ", exit stat 0
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Cleaning up...
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Killing kid4
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Process is dying with "Caught termination signal: Job canceled
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] ", exit stat 0
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Cleaning up...
D [26/Jan/2016:11:45:02 +0100] [Job 5375616] Killing pdf-to-ps
D [26/Jan/2016:11:45:09 +0100] [CGI] cgiSetArray: job_id[0]="5375616"
D [26/Jan/2016:11:45:10 +0100] [Job 5375616] Killing kid3
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] 3 filters for job:
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] pstopdf (application/postscript to application/pdf, cost 0)
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] foomatic-rip (application/vnd.cups-pdf to printer/p077_09, cost 0)
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] job-sheets=none,none
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] argv[0]="p077_09"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] argv[1]="5375616"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] argv[2]="PIREMOTE"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] argv[3]="002eyejR.DRP"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] argv[4]="1"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] argv[5]="document-name=002eyejR.DRP job-originating-host-name=dosap61.company.com job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c date-time-at-creation= date-time-at-processing= time-at-creation=1453727301 time-at-processing=1453804973 document-name-supplied= document-name-supplied= document-name-supplied="
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] argv[6]="/var/spool/cups/d5375616-002"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[7]="CUPS_STATEDIR=/run/cups"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[8]="HOME=/var/spool/cups/tmp"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[10]="SERVER_ADMIN=root@plato"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[11]="SOFTWARE=CUPS/1.7.5"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[13]="USER=root"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[14]="CUPS_MAX_MESSAGE=2047"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[17]="IPP_PORT=631"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[18]="CHARSET=utf-8"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[19]="LANG=en.UTF-8"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[20]="PPD=/etc/cups/ppd/p077_09.ppd"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[21]="RIP_MAX_CACHE=128m"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[22]="CONTENT_TYPE=application/postscript"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[23]="DEVICE_URI=socket://prt09.hit077.company.com:9100/?snmp=false"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[24]="PRINTER_INFO=Drucker zum Verschieben"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[25]="PRINTER_LOCATION="
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[26]="PRINTER=p077_09"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[27]="PRINTER_STATE_REASONS=none"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[28]="CUPS_FILETYPE=document"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] envp[30]="AUTH_I****"
I [26/Jan/2016:11:45:18 +0100] [Job 5375616] Started filter /usr/lib/cups/filter/pstopdf (PID 19849)
I [26/Jan/2016:11:45:18 +0100] [Job 5375616] Started filter /usr/lib/cups/filter/pdftopdf (PID 19850)
I [26/Jan/2016:11:45:18 +0100] [Job 5375616] Started filter /usr/lib/cups/filter/foomatic-rip (PID 19851)
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] PID 16510 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Calling FindDeviceById(cups-p077_09)
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Failed to get find device cups-p077_09
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] 'CM Color Calibration' Mode in SPOOLER-LESS: Off
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Getting input from file 
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] foomatic-rip version 1.0.58 running...
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Parsing PPD file ...
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option OptCST
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option ColorSpace
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] pstopdf 6 args: 5375616 PIREMOTE 002eyejR.DRP 1 document-name=002eyejR.DRP job-originating-host-name=dosap61.company.com job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c date-time-at-creation= date-time-at-processing= time-at-creation=1453727301 time-at-processing=1453804973 document-name-supplied= document-name-supplied= document-name-supplied= /var/spool/cups/d5375616-002
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] PPD: /etc/cups/ppd/p077_09.ppd
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option InputSlot
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option MediaType
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option Resolution
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option Duplex
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option BindingLocation
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option Gutter
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option CN_DPI
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option ImageRefine
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option Gscale
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option DotGAdjust
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option TonerSave
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option HalfTone
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option HalftoneType
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option ScreenProc
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option Transfer
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option LeadingEdge
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option PageSize
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option ImageableArea
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option PaperDimension
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option Font
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Added option ColorSep
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Parameter Summary
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] -----------------
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Spooler: cups
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Printer: p077_09
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Shell: /bin/bash
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] PPD file: /etc/cups/ppd/p077_09.ppd
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] ATTR file: 
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Printer model: Canon LBP6680/3480 PS
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Job title: 002eyejR.DRP
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] File(s) to be printed:
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] <STDIN>
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Printing system options:
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'document-name=002eyejR.DRP'
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option document-name=002eyejR.DRP.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'job-originating-host-name=dosap61.company.com'
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option job-originating-host-name=dosap61.company.com.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c'
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'date-time-at-creation='
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option date-time-at-creation=.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'date-time-at-processing='
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option date-time-at-processing=.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'time-at-creation=1453727301'
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option time-at-creation=1453727301.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'time-at-processing=1453804973'
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option time-at-processing=1453804973.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'document-name-supplied='
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option document-name-supplied=.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'document-name-supplied='
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option document-name-supplied=.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Pondering option 'document-name-supplied='
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Unknown option document-name-supplied=.
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] CM Color Calibration Mode in CUPS: Off
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Options from the PPD file:
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] ================================================
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] File: <STDIN>
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] ================================================
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Resolution: 600
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Orientation requested: 0
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Page size: A4
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Width: 595, height: 842, absolute margins: 11.3386, 11.3386, 583.6614, 830.6614
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Relative margins: 11.3386, 11.3386, 11.3386, 11.3386
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] PPD options: -r600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] PostScript to be injected: <</.HWMargins[11.3386 11.3386 11.3386 11.3386] /Margins[0 0]>>setpagedevice
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -dNOINTERPOLATE -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer -dUseCIEColor                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=-  -c <</.HWMargins[11.3386 11.3386 11.3386 11.3386] /Margins[0 0]>>setpagedevice .setpdfwrite -f -
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] GPL Ghostscript 9.15: 
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Use of -dUseCIEColor detected!
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] Since the release of version 9.11 of Ghostscript we recommend you do not set
D [26/Jan/2016:11:45:18 +0100] [Job 5375616] -dUseCIEColor with the pdfwrite/ps2write device family.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] GPL Ghostscript 9.15: 
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Use of -dUseCIEColor detected!
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Since the release of version 9.11 of Ghostscript we recommend you do not set
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] -dUseCIEColor with the pdfwrite/ps2write device family.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] GPL Ghostscript 9.15: 
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Use of -dUseCIEColor detected!
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Since the release of version 9.11 of Ghostscript we recommend you do not set
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] -dUseCIEColor with the pdfwrite/ps2write device family.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PID 19849 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Filetype: PDF
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PostScript option found: InputSlot=Auto: "<</InputAttributes <</Priority []>> >>
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] systemdict /setpagedevice get exec"
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Driver does not understand PDF input, converting to PostScript
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Storing temporary files in /var/spool/cups/tmp
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PID 19850 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Starting process "pdf-to-ps" (generation 1)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Printer make and model: Canon LBP6680/3480
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Running command line for pstops: pstops 5375616 PIREMOTE 002eyejR.DRP 1 ' document-name=002eyejR.DRP job-originating-host-name=dosap61.company.com job-uuid=urn:uuid:405a0e82-9c9a-33e5-6998-c1f92a7f500c date-time-at-creation= date-time-at-processing= time-at-creation=1453727301 time-at-processing=1453804973 document-name-supplied= document-name-supplied= document-name-supplied='
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Using image rendering resolution 600 dpi
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Running command line for pdftops: pdftops -level3 -origpagesizes -nocenter -r 600 /var/spool/cups/tmp/foomatic-1Lh1xe -
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Started filter pdftops (PID 19906)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Started filter pstops (PID 19907)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Page = 595x842; 11,11 to 584,831
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] slow_collate=0, slow_duplex=0, slow_order=0
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Before copy_comments - %!PS-Adobe-3.0
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %!PS-Adobe-3.0
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %Produced by poppler pdftops version: 0.24.4 (http://poppler.freedesktop.org)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %%LanguageLevel: 3
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %%DocumentSuppliedResources: (atend)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %%DocumentMedia: 595x842 595 842 0 () ()
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %%BoundingBox: 0 0 595 842
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %%Pages: 2
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] %%EndComments
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Before copy_prolog - %%BeginProlog
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Filetype: PostScript
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Reading PostScript input ...
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> This document is DSC-conforming!
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found %RBINumCopies: 1
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginProlog
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Inserting option code into "Prolog" section.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Before copy_setup - %%BeginSetup
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%EndProlog
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginSetup
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *OptCST False
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: OptCST=False
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *InputSlot Auto
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: InputSlot=Auto
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *Duplex None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: Duplex=None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *Gscale Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: Gscale=Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *Gutter None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: Gutter=None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *HalfTone Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: HalfTone=Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *ImageRefine Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: ImageRefine=Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *BindingLocation None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: BindingLocation=None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *MediaType None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: MediaType=None
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *DotGAdjust Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: DotGAdjust=Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *PageRegion A4
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: PageRegion=A4
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *TonerSave Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: TonerSave=Default
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginFeature: *CN_DPI 600dpi
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Option: CN_DPI=600dpi
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Option will be set by PostScript interpreter
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Before page loop - %%Page: 1 1
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Copying page 1...
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] pagew = 572.3, pagel = 819.3
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PageLeft = 11.3, PageRight = 583.7
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PageTop = 830.7, PageBottom = 11.3
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PageWidth = 595.0, PageLength = 842.0
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%EndSetup
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Inserting PostScript code for CUPS' page accounting
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Inserting option code into "Setup" section.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] New page: %%Page: 1 1
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginPageSetup
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Inserting option code into "PageSetup" section.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Copying page 2...
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] pagew = 572.3, pagel = 819.3
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PageLeft = 11.3, PageRight = 583.7
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PageTop = 830.7, PageBottom = 11.3
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PageWidth = 595.0, PageLength = 842.0
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Wrote 2 pages...
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PID 19906 (pdftops) exited with no errors.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] New page found but previous not printed, print it now.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%Page: 2 2
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Output goes directly to the renderer now.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] PID 19907 (pstops) exited with no errors.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Starting renderer with command: "/bin/cat - | sicgsfilter -MPS -NP  -uPIREMOTE -V"002eyejR.DRP" -n1 "
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Starting process "kid3" (generation 1)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Starting process "kid4" (generation 2)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] JCL: %-12345X@PJL
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Starting process "renderer" (generation 2)
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] <job data> 
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] -----------
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] New page: %%Page: 2 2
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%Page: 2 2
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] --> Output goes to the FIFO buffer now.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Found: %%BeginPageSetup
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Inserting option code into "PageSetup" section.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Flushing FIFO.
D [26/Jan/2016:11:45:19 +0100] [Job 5375616] Closing renderer
E [26/Jan/2016:11:50:02 +0100] [Job 5375616] Stopping unresponsive job.
D [26/Jan/2016:11:50:02 +0100] [Job 5375616] PID 16511 (/usr/lib/cups/backend/socket) was terminated normally with signal 9.
D [26/Jan/2016:11:50:02 +0100] [Job 5375616] PID 19851 (/usr/lib/cups/filter/foomatic-rip) was terminated normally with signal 9.
D [26/Jan/2016:11:57:43 +0100] [CGI] cgiSetArray: job_id[0]="5375616"