[cups] StartOutput output_header_error
Michael Sweet
msweet at apple.com
Sat Sep 6 05:12:03 PDT 2014
Looks like Ghostscript is failing. Beyond that I can't say,
Sent from my iPad
> On Sep 6, 2014, at 5:03 AM, Håkon Alstadheim <hakon at alstadheim.priv.no> wrote:
>
> Hi all, I'm new to the list, apologies if this has been asked and answered. After intensive googling (few hits from this list) I have not found an answer.
>
> Problem: Only root can print.
> Gear: networked canon i-sensys lbp7100cn
> linux cups server on debian wheezy 32-bit (with some stuff from backports, amd64 kernel from plain wheezy)
> .debs from canon installed
> print-client is on then same machine
> user root can print (see "Successful job" below)
> user hakon can not (see "Failed job" below)
> command $ id gives:
> uid=1001(hakon) gid=1001(hakon) groups=1001(hakon),0(root),4(adm),7(lp),27(sudo),29(audio),37(operator),44(video),50(staff),123(fuse),131(lpadmin),139(quaggavty),143(wireshark),162(davfs2),1011(realtime)t print.
>
> looking at the various paths from the debug output like qouted below, seems to indicate that "hakon" has all the required permissions, and that there is plenty of free space (see "various paths in cups" below).
>
> The successful and failed jobs start to diverge (as far as I can see) at the line where the failure says "StartOutput output_header_error". That string gives no hits in the ml-archive.
>
> I have tried running the cups server under strace with -e open,write, but the output is a bit staggering, and there are no obvous negative return values jumping out at me.
>
> So, how do I go about debugging this further ? Which settings in cupsd.conf affect this? I have tried resetting the cupsd.conf to default from the web-interface, and then removed "Port 631", and put in "Listen localhost:631" and "Listen 192.168.2.2:631". The box is directly connected to the internet, and I am a bit leery about letting cups listen to all interfaces.
>
>
> --------------------------command to examine the various paths in cups:--------
> for f in /var/cache/cups /usr/share/cups /usr/share/cups/doc-root /usr/share/cups/fonts /var/spool/cups /usr/lib/cups /etc/cups /var/run/cups /var/spool/cups/tmp /usr/lib/cups/filter /usr/bin /usr/sbin /bin /usr/bin /var/spool/cups/tmp /var/run/cups/cups.sock /etc/cups/ppd/Canon_LBP7100C_7110C.ppd /usr/lib/cups/filter/pdftopdf /usr/lib/cups/filter/pdftops /usr/lib/cups/filter/pstoncapcpca /usr/lib/cups/backend/socket; do if test -d "$f"; then ls -ld $f;echo -n "df -h $f: "; df -h $f| tail -1; else ls -ld $f;fi;done
> drwxrwxr-x 3 root lp 4096 2014-09-06 10:34 /var/cache/cups
> df -h /var/cache/cups: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 17 root root 4096 2013-05-13 15:13 /usr/share/cups
> df -h /usr/share/cups: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 14 root root 4096 2014-07-29 21:19 /usr/share/cups/doc-root
> df -h /usr/share/cups/doc-root: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 2 root root 4096 2014-03-22 12:07 /usr/share/cups/fonts
> df -h /usr/share/cups/fonts: /dev/mapper/system-root 311G 167G 130G 57% /
> drwx--x--- 3 root lp 4096 2014-09-06 10:33 /var/spool/cups
> df -h /var/spool/cups: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 10 root root 4096 2012-06-03 11:31 /usr/lib/cups
> df -h /usr/lib/cups: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 4 root lp 4096 2014-09-06 10:34 /etc/cups
> df -h /etc/cups: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 3 root lp 120 2014-09-06 07:18 /var/run/cups
> df -h /var/run/cups: tmpfs 369M 6.4M 362M 2% /run
> drwxrwx--T 2 root lp 4096 2014-09-06 10:33 /var/spool/cups/tmp
> df -h /var/spool/cups/tmp: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 2 root root 4096 2014-09-05 12:20 /usr/lib/cups/filter
> df -h /usr/lib/cups/filter: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 3 root root 122880 2014-09-05 12:20 /usr/bin
> df -h /usr/bin: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 2 root root 20480 2014-09-05 12:14 /usr/sbin
> df -h /usr/sbin: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 2 root root 4096 2014-08-06 13:31 /bin
> df -h /bin: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxr-xr-x 3 root root 122880 2014-09-05 12:20 /usr/bin
> df -h /usr/bin: /dev/mapper/system-root 311G 167G 130G 57% /
> drwxrwx--T 2 root lp 4096 2014-09-06 10:33 /var/spool/cups/tmp
> df -h /var/spool/cups/tmp: /dev/mapper/system-root 311G 167G 130G 57% /
> srwxrwxrwx 1 root root 0 2014-09-06 07:18 /var/run/cups/cups.sock
> -rw-r--r-- 1 root root 25646 2014-09-05 13:32 /etc/cups/ppd/Canon_LBP7100C_7110C.ppd
> -rwxr-xr-x 1 root root 156320 2014-03-11 15:30 /usr/lib/cups/filter/pdftopdf
> -rwxr-xr-x 1 root root 26408 2014-03-11 15:30 /usr/lib/cups/filter/pdftops
> -rwxr-xr-x 1 root root 31004 2014-03-24 11:53 /usr/lib/cups/filter/pstoncapcpca
> -r-xr-xr-x 2 root root 30160 2014-07-25 10:13 /usr/lib/cups/backend/socket
>
> --- Failed job: -----
> D [05/Sep/2014:15:51:42 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:51:42 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:51:42 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:51:42 +0200] cupsdReadClient: 17 WAITING Closing on EOF
> D [05/Sep/2014:15:51:42 +0200] cupsdCloseClient: 17
> D [05/Sep/2014:15:51:42 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:51:44 +0200] cupsdAcceptClient: 16 from localhost (Domain)
> D [05/Sep/2014:15:51:44 +0200] cupsdReadClient: 16 POST /printers/Canon_LBP7100C_7110C HTTP/1.1
> D [05/Sep/2014:15:51:44 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:51:44 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:51:44 +0200] cupsdReadClient: 16 1.1 Print-Job 1
> D [05/Sep/2014:15:51:45 +0200] Print-Job ipp://localhost:631/printers/Canon_LBP7100C_7110C
> D [05/Sep/2014:15:51:45 +0200] [Job ???] Auto-typing file...
> I [05/Sep/2014:15:51:45 +0200] [Job ???] Request file type is application/pdf.
> D [05/Sep/2014:15:51:45 +0200] cupsdMarkDirty(----J-)
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
> D [05/Sep/2014:15:51:45 +0200] add_job: requesting-user-name="hakon"
> I [05/Sep/2014:15:51:45 +0200] [Job 339] Adding start banner page "none".
> D [05/Sep/2014:15:51:45 +0200] Discarding unused job-created event...
> D [05/Sep/2014:15:51:45 +0200] cupsdMarkDirty(----J-)
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
> I [05/Sep/2014:15:51:45 +0200] [Job 339] Adding end banner page "none".
> I [05/Sep/2014:15:51:45 +0200] [Job 339] File of type application/pdf queued by "hakon".
> D [05/Sep/2014:15:51:45 +0200] [Job 339] hold_until=0
> I [05/Sep/2014:15:51:45 +0200] [Job 339] Queued on "Canon_LBP7100C_7110C" by "hakon".
> D [05/Sep/2014:15:51:45 +0200] cupsdMarkDirty(----J-)
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:51:45 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:51:45 +0200] [Job 339] job-sheets=none,none
> D [05/Sep/2014:15:51:45 +0200] [Job 339] argv[0]="Canon_LBP7100C_7110C"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] argv[1]="339"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] argv[2]="hakon"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] argv[3]="Uten navn"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] argv[4]="1"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] argv[5]="InputSlot=Auto CNColorMode=Auto noCNFeedAFiveHorizontally PageSize=A4 number-up=1 MediaType=Auto CNDraftModeTwo=Off CNBarCodeMode=None noCNFeedStatementHorizontally CNDetectPaperSize OutputBin=Auto job-uuid=urn:uuid:3c871046-377d-353a-68f7-c04ac0764728 job-originating-host-name=localhost time-at-creation=1409925105 time-at-processing=1409925105"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] argv[6]="/var/spool/cups/d00339-001"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[8]="HOME=/var/spool/cups/tmp"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[10]="SERVER_ADMIN=root at garbo"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[11]="SOFTWARE=CUPS/1.5.3"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[13]="TZ=Europe/Oslo"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[14]="USER=root"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[16]="CUPS_ENCRYPTION=IfRequested"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[17]="IPP_PORT=631"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[18]="CHARSET=utf-8"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[19]="LANG=no_NO.UTF-8"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[20]="PPD=/etc/cups/ppd/Canon_LBP7100C_7110C.ppd"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[21]="RIP_MAX_CACHE=128m"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[22]="CONTENT_TYPE=application/pdf"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[23]="DEVICE_URI=socket://192.168.2.202"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[24]="PRINTER_INFO=Canon LBP7100C/7110C"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[25]="PRINTER_LOCATION=Loftet"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[26]="PRINTER=Canon_LBP7100C_7110C"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[27]="PRINTER_STATE_REASONS=none"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[28]="CUPS_FILETYPE=document"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[29]="FINAL_CONTENT_TYPE=printer/Canon_LBP7100C_7110C"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] envp[30]="AUTH_I****"
> I [05/Sep/2014:15:51:45 +0200] [Job 339] Started filter /usr/lib/cups/filter/pdftopdf (PID 24829)
> I [05/Sep/2014:15:51:45 +0200] [Job 339] Started filter /usr/lib/cups/filter/pdftops (PID 24830)
> I [05/Sep/2014:15:51:45 +0200] [Job 339] Started filter /usr/lib/cups/filter/pstoncapcpca (PID 24831)
> I [05/Sep/2014:15:51:45 +0200] [Job 339] Started backend /usr/lib/cups/backend/socket (PID 24832)
> D [05/Sep/2014:15:51:45 +0200] Discarding unused job-state-changed event...
> D [05/Sep/2014:15:51:45 +0200] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/Canon_LBP7100C_7110C) from localhost
> D [05/Sep/2014:15:51:45 +0200] [Job 339] pstoncapcpca start.
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:51:45 +0200] cupsdReadClient: 16 WAITING Closing on EOF
> D [05/Sep/2014:15:51:45 +0200] cupsdCloseClient: 16
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: +connecting-to-device
> D [05/Sep/2014:15:51:45 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:51:45 +0200] [Job 339] Looking up "192.168.2.202"...
> D [05/Sep/2014:15:51:45 +0200] [Job 339] pdftops - copying to temp print file "/var/spool/cups/tmp/060fe540c7071"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] ATTR: marker-colors=#000000,#00FFFF,#FF00FF,#FFFF00
> D [05/Sep/2014:15:51:45 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:51:45 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:51:45 +0200] [Job 339] ATTR: marker-names='"Canon CRG731 Black Toner for LBP7100Cn"','"Canon CRG731 Cyan Toner for LBP7100Cn"','"Canon CRG731 Magenta Toner for LBP7100Cn"','"Canon CRG731 Yellow Toner for LBP7100Cn"'
> D [05/Sep/2014:15:51:45 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] ATTR: marker-types=toner,toner,toner,toner
> D [05/Sep/2014:15:51:45 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:51:45 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:51:45 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:45 +0200] [Job 339] new_supply_state=0, change_state=ffff
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -developer-low-report
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -developer-empty-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -marker-supply-low-report
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -marker-supply-empty-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -opc-near-eol-report
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -opc-life-over-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -toner-low-report
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -toner-empty-warning
> D [05/Sep/2014:15:51:45 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:51:45 +0200] [Job 339] new_state=0, change_state=ffff
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -media-low-report
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -media-empty-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -door-open-report
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -media-jam-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -input-tray-missing-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -output-tray-missing-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -marker-supply-missing-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -output-area-almost-full-report
> D [05/Sep/2014:15:51:45 +0200] [Job 339] STATE: -output-area-full-warning
> D [05/Sep/2014:15:51:45 +0200] [Job 339] backendWaitLoop(snmp_fd=5, addr=0xf97a797c, side_cb=0xf77d67b0)
> D [05/Sep/2014:15:51:45 +0200] PID 24829 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
> D [05/Sep/2014:15:51:45 +0200] [Job 339] Using image rendering resolution 600 dpi
> D [05/Sep/2014:15:51:45 +0200] [Job 339] Started filter gs (PID 24834)
> D [05/Sep/2014:15:51:45 +0200] [Job 339] Started filter pstops (PID 24835)
> D [05/Sep/2014:15:51:49 +0200] [Job 339] Page = 595x842; 14,14 to 581,828
> D [05/Sep/2014:15:51:49 +0200] [Job 339] slow_collate=0, slow_duplex=0, slow_order=0
> D [05/Sep/2014:15:51:50 +0200] [Job 339] Before copy_comments - %!PS-Adobe-3.0
> D [05/Sep/2014:15:51:50 +0200] [Job 339] %!PS-Adobe-3.0
> D [05/Sep/2014:15:51:50 +0200] [Job 339] %%BoundingBox: 0 0 595 842
> D [05/Sep/2014:15:51:50 +0200] [Job 339] %%Creator: GPL Ghostscript 905 (ps2write)
> D [05/Sep/2014:15:51:50 +0200] [Job 339] %%LanguageLevel: 2
> D [05/Sep/2014:15:51:50 +0200] [Job 339] %%CreationDate: D:20140905155146+02'00'
> D [05/Sep/2014:15:51:50 +0200] [Job 339] %%Pages: 1
> D [05/Sep/2014:15:51:50 +0200] [Job 339] %%EndComments
> D [05/Sep/2014:15:51:50 +0200] [Job 339] Before copy_prolog - %%BeginProlog
> D [05/Sep/2014:15:51:50 +0200] [Job 339] Before copy_setup - %%Page: 1 1
> D [05/Sep/2014:15:51:50 +0200] [Job 339] Before page loop - %%Page: 1 1
> D [05/Sep/2014:15:51:50 +0200] [Job 339] Copying page 1...
> D [05/Sep/2014:15:51:50 +0200] [Job 339] pagew = 566.9, pagel = 813.5
> D [05/Sep/2014:15:51:50 +0200] [Job 339] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
> D [05/Sep/2014:15:51:50 +0200] [Job 339] PageLeft = 14.2, PageRight = 581.1
> D [05/Sep/2014:15:51:50 +0200] [Job 339] PageTop = 827.8, PageBottom = 14.3
> D [05/Sep/2014:15:51:50 +0200] [Job 339] PageWidth = 595.0, PageLength = 842.0
> D [05/Sep/2014:15:51:50 +0200] [Job 339] opvpOpenPrinter(463)
> D [05/Sep/2014:15:51:50 +0200] [Job 339] CStubOpenPrinter(397)
> D [05/Sep/2014:15:51:50 +0200] [Job 339] opvpOpenPrinter(463)
> D [05/Sep/2014:15:51:50 +0200] [Job 339] CStubOpenPrinter(397)
> D [05/Sep/2014:15:51:50 +0200] [Job 339] StartOutput output_header_error
> E [05/Sep/2014:15:51:50 +0200] [Job 339] src = libcanon_pdlwrapper.c, line = 633, err = -1¥nDEBUG2: prtMarkerSuppliesLevel.1.1 = 100
> D [05/Sep/2014:15:51:50 +0200] [Job 339] Set job-printer-state-message to "src = libcanon_pdlwrapper.c, line = 633, err = -1¥nDEBUG2: prtMarkerSuppliesLevel.1.1 = 100", current level=ERROR
> D [05/Sep/2014:15:51:50 +0200] Discarding unused job-progress event...
> D [05/Sep/2014:15:51:50 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:51:50 +0200] [Job 339] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:51:50 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:51:50 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:50 +0200] [Job 339] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:51:50 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:51:50 +0200] [Job 339] new_state=0, change_state=0
> D [05/Sep/2014:15:51:52 +0200] cupsdAcceptClient: 16 from localhost (Domain)
> D [05/Sep/2014:15:51:52 +0200] Report: clients=1
> D [05/Sep/2014:15:51:52 +0200] Report: jobs=62
> D [05/Sep/2014:15:51:52 +0200] Report: jobs-active=1
> D [05/Sep/2014:15:51:52 +0200] Report: printers=2
> D [05/Sep/2014:15:51:52 +0200] Report: printers-implicit=0
> D [05/Sep/2014:15:51:52 +0200] Report: stringpool-string-count=2999
> D [05/Sep/2014:15:51:52 +0200] Report: stringpool-alloc-bytes=13520
> D [05/Sep/2014:15:51:52 +0200] Report: stringpool-total-bytes=55496
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:51:52 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:51:52 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
> D [05/Sep/2014:15:51:52 +0200] CUPS-Get-Default
> D [05/Sep/2014:15:51:52 +0200] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
> D [05/Sep/2014:15:51:52 +0200] Get-Printer-Attributes ipp://localhost/printers/Canon_LBP7100C_7110C
> D [05/Sep/2014:15:51:52 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Canon_LBP7100C_7110C) from localhost
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 1.1 Get-Jobs 1
> D [05/Sep/2014:15:51:52 +0200] Get-Jobs ipp://localhost/printers/Canon_LBP7100C_7110C
> D [05/Sep/2014:15:51:52 +0200] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Canon_LBP7100C_7110C) from localhost
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:51:52 +0200] cupsdReadClient: 16 WAITING Closing on EOF
> D [05/Sep/2014:15:51:52 +0200] cupsdCloseClient: 16
> D [05/Sep/2014:15:51:52 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:55 +0200] [Job 339] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:51:55 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:51:55 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:51:55 +0200] [Job 339] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:51:55 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:51:55 +0200] [Job 339] new_state=0, change_state=0
> D [05/Sep/2014:15:52:00 +0200] [Job 339] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:52:00 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:52:00 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:52:00 +0200] [Job 339] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:52:00 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:52:00 +0200] [Job 339] new_state=0, change_state=0
> D [05/Sep/2014:15:52:05 +0200] [Job 339] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:52:05 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:52:05 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:52:05 +0200] [Job 339] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:52:05 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:52:05 +0200] [Job 339] new_state=0, change_state=0
> D [05/Sep/2014:15:52:08 +0200] [Job 339] PID 24834 (gs) exited with no errors.
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Wrote 1 pages...
> D [05/Sep/2014:15:52:08 +0200] [Job 339] PID 24835 (pstops) exited with no errors.
> D [05/Sep/2014:15:52:08 +0200] PID 24830 (/usr/lib/cups/filter/pdftops) exited with no errors.
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=2, SeqNo=3,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=5, SeqNo=4,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=7, SeqNo=121,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=8, SeqNo=2599,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=7, SeqNo=2600,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=8, SeqNo=2618,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] [Job 339] GPL Ghostscript 9.05: Unrecoverable error, exit code 1
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=6, SeqNo=2619,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] [Job 339] Error Response:ReqNo=3, SeqNo=2620,opvpErrorNo=-2
> D [05/Sep/2014:15:52:08 +0200] PID 24831 (/usr/lib/cups/filter/pstoncapcpca) exited with no errors.
> D [05/Sep/2014:15:52:08 +0200] PID 24832 (/usr/lib/cups/backend/socket) exited with no errors.
> D [05/Sep/2014:15:52:08 +0200] Discarding unused job-completed event...
> I [05/Sep/2014:15:52:08 +0200] [Job 339] Job completed.
> D [05/Sep/2014:15:52:08 +0200] cupsdMarkDirty(----J-)
> D [05/Sep/2014:15:52:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
> D [05/Sep/2014:15:52:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:52:08 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:52:09 +0200] [Job 339] Unloading...
> D [05/Sep/2014:15:52:09 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:52:09 +0200] cupsdNetIFUpdate: "eth2" = 178.164.66.9:631
> D [05/Sep/2014:15:52:09 +0200] cupsdNetIFUpdate: "br0" = 192.168.2.2:631
> D [05/Sep/2014:15:52:09 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:52:09 +0200] cupsdNetIFUpdate: "eth2" = [v1.fe80::92e2:baff:fe70:df29+eth2]:631
> D [05/Sep/2014:15:52:09 +0200] cupsdNetIFUpdate: "br0" = [v1.fe80::21d:92ff:feda:2afb+br0]:631
> I [05/Sep/2014:15:52:16 +0200] Saving printers.conf...
> I [05/Sep/2014:15:52:16 +0200] Saving job.cache...
> D [05/Sep/2014:15:52:16 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
>
>
>
> ------- Successful job: ------------
> D [05/Sep/2014:15:52:52 +0200] Report: clients=0
> D [05/Sep/2014:15:52:52 +0200] Report: jobs=62
> D [05/Sep/2014:15:52:52 +0200] Report: jobs-active=0
> D [05/Sep/2014:15:52:52 +0200] Report: printers=2
> D [05/Sep/2014:15:52:52 +0200] Report: printers-implicit=0
> D [05/Sep/2014:15:52:52 +0200] Report: stringpool-string-count=2945
> D [05/Sep/2014:15:52:52 +0200] Report: stringpool-alloc-bytes=12360
> D [05/Sep/2014:15:52:52 +0200] Report: stringpool-total-bytes=54384
> D [05/Sep/2014:15:52:55 +0200] cupsdAcceptClient: 16 from localhost (Domain)
> D [05/Sep/2014:15:52:55 +0200] cupsdReadClient: 16 POST / HTTP/1.1
> D [05/Sep/2014:15:52:55 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:52:55 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:52:55 +0200] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:52:55 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:52:55 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:52:55 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:52:57 +0200] cupsdAcceptClient: 17 from localhost (Domain)
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 POST / HTTP/1.1
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:52:57 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:52:57 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:52:57 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 POST / HTTP/1.1
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:52:57 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
> D [05/Sep/2014:15:52:57 +0200] CUPS-Get-Default
> D [05/Sep/2014:15:52:57 +0200] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 POST / HTTP/1.1
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:52:57 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
> D [05/Sep/2014:15:52:57 +0200] Get-Printer-Attributes ipp://localhost/printers/Canon_LBP7100C_7110C
> D [05/Sep/2014:15:52:57 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Canon_LBP7100C_7110C) from localhost
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 POST / HTTP/1.1
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:52:57 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 1.1 Get-Jobs 1
> D [05/Sep/2014:15:52:57 +0200] Get-Jobs ipp://localhost/printers/Canon_LBP7100C_7110C
> D [05/Sep/2014:15:52:57 +0200] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Canon_LBP7100C_7110C) from localhost
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:52:57 +0200] cupsdReadClient: 17 WAITING Closing on EOF
> D [05/Sep/2014:15:52:57 +0200] cupsdCloseClient: 17
> D [05/Sep/2014:15:52:57 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:53:00 +0200] cupsdAcceptClient: 17 from localhost (Domain)
> D [05/Sep/2014:15:53:00 +0200] cupsdReadClient: 17 POST / HTTP/1.1
> D [05/Sep/2014:15:53:00 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:53:00 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:53:00 +0200] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:53:00 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:53:00 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:53:00 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:53:06 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:53:06 +0200] cupsdReadClient: 18 POST / HTTP/1.1
> D [05/Sep/2014:15:53:06 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:53:06 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:53:06 +0200] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:53:06 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:53:06 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:53:06 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:53:06 +0200] cupsdReadClient: 18 POST / HTTP/1.1
> D [05/Sep/2014:15:53:06 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:53:06 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:53:06 +0200] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
> D [05/Sep/2014:15:53:06 +0200] CUPS-Get-Classes
> D [05/Sep/2014:15:53:06 +0200] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
> D [05/Sep/2014:15:53:06 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:53:06 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:53:06 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:53:06 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:53:11 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:53:11 +0200] cupsdNetIFUpdate: "eth2" = 178.164.66.9:631
> D [05/Sep/2014:15:53:11 +0200] cupsdNetIFUpdate: "br0" = 192.168.2.2:631
> D [05/Sep/2014:15:53:11 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:53:11 +0200] cupsdNetIFUpdate: "eth2" = [v1.fe80::92e2:baff:fe70:df29+eth2]:631
> D [05/Sep/2014:15:53:11 +0200] cupsdNetIFUpdate: "br0" = [v1.fe80::21d:92ff:feda:2afb+br0]:631
> D [05/Sep/2014:15:54:14 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:54:14 +0200] cupsdNetIFUpdate: "eth2" = 178.164.66.9:631
> D [05/Sep/2014:15:54:14 +0200] cupsdNetIFUpdate: "br0" = 192.168.2.2:631
> D [05/Sep/2014:15:54:14 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:54:14 +0200] cupsdNetIFUpdate: "eth2" = [v1.fe80::92e2:baff:fe70:df29+eth2]:631
> D [05/Sep/2014:15:54:14 +0200] cupsdNetIFUpdate: "br0" = [v1.fe80::21d:92ff:feda:2afb+br0]:631
> D [05/Sep/2014:15:54:14 +0200] Report: clients=2
> D [05/Sep/2014:15:54:14 +0200] Report: jobs=62
> D [05/Sep/2014:15:54:14 +0200] Report: jobs-active=0
> D [05/Sep/2014:15:54:14 +0200] Report: printers=2
> D [05/Sep/2014:15:54:14 +0200] Report: printers-implicit=0
> D [05/Sep/2014:15:54:14 +0200] Report: stringpool-string-count=2945
> D [05/Sep/2014:15:54:14 +0200] Report: stringpool-alloc-bytes=12360
> D [05/Sep/2014:15:54:14 +0200] Report: stringpool-total-bytes=54384
> D [05/Sep/2014:15:55:16 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:55:16 +0200] cupsdNetIFUpdate: "eth2" = 178.164.66.9:631
> D [05/Sep/2014:15:55:16 +0200] cupsdNetIFUpdate: "br0" = 192.168.2.2:631
> D [05/Sep/2014:15:55:16 +0200] cupsdNetIFUpdate: "lo" = localhost:631
> D [05/Sep/2014:15:55:16 +0200] cupsdNetIFUpdate: "eth2" = [v1.fe80::92e2:baff:fe70:df29+eth2]:631
> D [05/Sep/2014:15:55:16 +0200] cupsdNetIFUpdate: "br0" = [v1.fe80::21d:92ff:feda:2afb+br0]:631
> D [05/Sep/2014:15:55:16 +0200] Report: clients=2
> D [05/Sep/2014:15:55:16 +0200] Report: jobs=62
> D [05/Sep/2014:15:55:16 +0200] Report: jobs-active=0
> D [05/Sep/2014:15:55:16 +0200] Report: printers=2
> D [05/Sep/2014:15:55:16 +0200] Report: printers-implicit=0
> D [05/Sep/2014:15:55:16 +0200] Report: stringpool-string-count=2945
> D [05/Sep/2014:15:55:16 +0200] Report: stringpool-alloc-bytes=12360
> D [05/Sep/2014:15:55:16 +0200] Report: stringpool-total-bytes=54384
> D [05/Sep/2014:15:56:08 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:08 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:08 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 18 POST / HTTP/1.1
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:08 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:08 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:08 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:08 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:08 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 19 GET /printers/Canon_LBP7100C_7110C.ppd HTTP/1.1
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:08 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:08 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:08 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:08 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:08 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:08 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:08 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:08 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:08 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:08 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:08 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:09 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:09 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:09 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:09 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:09 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:09 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:09 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:09 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:09 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:09 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:09 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:09 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:09 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:09 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:10 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:10 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:10 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:10 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:10 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:10 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:10 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:10 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:10 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:10 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:10 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:10 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:10 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:10 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:11 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 18 POST / HTTP/1.1
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:11 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:11 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:11 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:11 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:11 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:11 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:11 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:11 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:11 +0200] cupsdAcceptClient: 19 from localhost (Domain)
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:11 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 19 POST / HTTP/1.1
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:11 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
> D [05/Sep/2014:15:56:11 +0200] CUPS-Get-Printers
> D [05/Sep/2014:15:56:11 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
> D [05/Sep/2014:15:56:11 +0200] cupsdReadClient: 19 WAITING Closing on EOF
> D [05/Sep/2014:15:56:11 +0200] cupsdCloseClient: 19
> D [05/Sep/2014:15:56:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
> D [05/Sep/2014:15:56:13 +0200] cupsdAcceptClient: 18 from localhost (Domain)
> D [05/Sep/2014:15:56:13 +0200] cupsdReadClient: 18 POST /printers/Canon_LBP7100C_7110C HTTP/1.1
> D [05/Sep/2014:15:56:13 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
> D [05/Sep/2014:15:56:13 +0200] cupsdAuthorize: No authentication data provided.
> D [05/Sep/2014:15:56:13 +0200] cupsdReadClient: 18 1.1 Print-Job 1
> D [05/Sep/2014:15:56:14 +0200] Print-Job ipp://localhost:631/printers/Canon_LBP7100C_7110C
> D [05/Sep/2014:15:56:14 +0200] [Job ???] Auto-typing file...
> I [05/Sep/2014:15:56:14 +0200] [Job ???] Request file type is application/pdf.
> D [05/Sep/2014:15:56:14 +0200] cupsdMarkDirty(----J-)
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
> D [05/Sep/2014:15:56:14 +0200] add_job: requesting-user-name="root"
> I [05/Sep/2014:15:56:14 +0200] [Job 340] Adding start banner page "none".
> D [05/Sep/2014:15:56:14 +0200] Discarding unused job-created event...
> D [05/Sep/2014:15:56:14 +0200] cupsdMarkDirty(----J-)
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
> I [05/Sep/2014:15:56:14 +0200] [Job 340] Adding end banner page "none".
> I [05/Sep/2014:15:56:14 +0200] [Job 340] File of type application/pdf queued by "root".
> D [05/Sep/2014:15:56:14 +0200] [Job 340] hold_until=0
> I [05/Sep/2014:15:56:14 +0200] [Job 340] Queued on "Canon_LBP7100C_7110C" by "root".
> D [05/Sep/2014:15:56:14 +0200] cupsdMarkDirty(----J-)
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:56:14 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:14 +0200] [Job 340] job-sheets=none,none
> D [05/Sep/2014:15:56:14 +0200] [Job 340] argv[0]="Canon_LBP7100C_7110C"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] argv[1]="340"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] argv[2]="root"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] argv[3]="Untitled"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] argv[4]="1"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] argv[5]="InputSlot=Auto CNColorMode=Auto noCNFeedAFiveHorizontally PageSize=A4 number-up=1 MediaType=Auto CNDraftModeTwo=Off CNBarCodeMode=None noCNFeedStatementHorizontally CNDetectPaperSize OutputBin=Auto job-uuid=urn:uuid:33d62377-cec8-3aa4-4d0c-75b1a2448c7a job-originating-host-name=localhost time-at-creation=1409925374 time-at-processing=1409925374"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] argv[6]="/var/spool/cups/d00340-001"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[8]="HOME=/var/spool/cups/tmp"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[10]="SERVER_ADMIN=root at garbo"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[11]="SOFTWARE=CUPS/1.5.3"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[12]="TMPDIR=/var/spool/cups/tmp"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[13]="TZ=Europe/Oslo"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[14]="USER=root"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[16]="CUPS_ENCRYPTION=IfRequested"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[17]="IPP_PORT=631"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[18]="CHARSET=utf-8"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[19]="LANG=en_US.UTF-8"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[20]="PPD=/etc/cups/ppd/Canon_LBP7100C_7110C.ppd"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[21]="RIP_MAX_CACHE=128m"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[22]="CONTENT_TYPE=application/pdf"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[23]="DEVICE_URI=socket://192.168.2.202"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[24]="PRINTER_INFO=Canon LBP7100C/7110C"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[25]="PRINTER_LOCATION=Loftet"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[26]="PRINTER=Canon_LBP7100C_7110C"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[27]="PRINTER_STATE_REASONS=none"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[28]="CUPS_FILETYPE=document"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[29]="FINAL_CONTENT_TYPE=printer/Canon_LBP7100C_7110C"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] envp[30]="AUTH_I****"
> I [05/Sep/2014:15:56:14 +0200] [Job 340] Started filter /usr/lib/cups/filter/pdftopdf (PID 25911)
> I [05/Sep/2014:15:56:14 +0200] [Job 340] Started filter /usr/lib/cups/filter/pdftops (PID 25912)
> I [05/Sep/2014:15:56:14 +0200] [Job 340] Started filter /usr/lib/cups/filter/pstoncapcpca (PID 25913)
> I [05/Sep/2014:15:56:14 +0200] [Job 340] Started backend /usr/lib/cups/backend/socket (PID 25914)
> D [05/Sep/2014:15:56:14 +0200] Discarding unused job-state-changed event...
> D [05/Sep/2014:15:56:14 +0200] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/Canon_LBP7100C_7110C) from localhost
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
> D [05/Sep/2014:15:56:14 +0200] cupsdReadClient: 18 WAITING Closing on EOF
> D [05/Sep/2014:15:56:14 +0200] cupsdCloseClient: 18
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: +connecting-to-device
> D [05/Sep/2014:15:56:14 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:14 +0200] [Job 340] pstoncapcpca start.
> D [05/Sep/2014:15:56:14 +0200] [Job 340] pdftops - copying to temp print file "/var/spool/cups/tmp/06538540c1283"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] Looking up "192.168.2.202"...
> D [05/Sep/2014:15:56:14 +0200] [Job 340] ATTR: marker-colors=#000000,#00FFFF,#FF00FF,#FFFF00
> D [05/Sep/2014:15:56:14 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] ATTR: marker-names='"Canon CRG731 Black Toner for LBP7100Cn"','"Canon CRG731 Cyan Toner for LBP7100Cn"','"Canon CRG731 Magenta Toner for LBP7100Cn"','"Canon CRG731 Yellow Toner for LBP7100Cn"'
> D [05/Sep/2014:15:56:14 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] ATTR: marker-types=toner,toner,toner,toner
> D [05/Sep/2014:15:56:14 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:56:14 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
> D [05/Sep/2014:15:56:14 +0200] [Job 340] new_supply_state=0, change_state=ffff
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -developer-low-report
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -developer-empty-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -marker-supply-low-report
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -marker-supply-empty-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -opc-near-eol-report
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -opc-life-over-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -toner-low-report
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -toner-empty-warning
> D [05/Sep/2014:15:56:14 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:14 +0200] [Job 340] new_state=0, change_state=ffff
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -media-low-report
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -media-empty-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -door-open-report
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -media-jam-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -input-tray-missing-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -output-tray-missing-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -marker-supply-missing-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -output-area-almost-full-report
> D [05/Sep/2014:15:56:14 +0200] [Job 340] STATE: -output-area-full-warning
> D [05/Sep/2014:15:56:14 +0200] [Job 340] backendWaitLoop(snmp_fd=5, addr=0xf7acb97c, side_cb=0xf76fe7b0)
> D [05/Sep/2014:15:56:14 +0200] PID 25911 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
> D [05/Sep/2014:15:56:14 +0200] [Job 340] Using image rendering resolution 600 dpi
> D [05/Sep/2014:15:56:14 +0200] [Job 340] Started filter gs (PID 25915)
> D [05/Sep/2014:15:56:14 +0200] [Job 340] Started filter pstops (PID 25916)
> D [05/Sep/2014:15:56:16 +0200] Report: clients=2
> D [05/Sep/2014:15:56:16 +0200] Report: jobs=63
> D [05/Sep/2014:15:56:16 +0200] Report: jobs-active=1
> D [05/Sep/2014:15:56:16 +0200] Report: printers=2
> D [05/Sep/2014:15:56:16 +0200] Report: printers-implicit=0
> D [05/Sep/2014:15:56:16 +0200] Report: stringpool-string-count=3001
> D [05/Sep/2014:15:56:16 +0200] Report: stringpool-alloc-bytes=13400
> D [05/Sep/2014:15:56:16 +0200] Report: stringpool-total-bytes=55440
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Page = 595x842; 14,14 to 581,828
> D [05/Sep/2014:15:56:18 +0200] [Job 340] slow_collate=0, slow_duplex=0, slow_order=0
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Before copy_comments - %!PS-Adobe-3.0
> D [05/Sep/2014:15:56:18 +0200] [Job 340] %!PS-Adobe-3.0
> D [05/Sep/2014:15:56:18 +0200] [Job 340] %%BoundingBox: 0 0 595 842
> D [05/Sep/2014:15:56:18 +0200] [Job 340] %%Creator: GPL Ghostscript 905 (ps2write)
> D [05/Sep/2014:15:56:18 +0200] [Job 340] %%LanguageLevel: 2
> D [05/Sep/2014:15:56:18 +0200] [Job 340] %%CreationDate: D:20140905155614+02'00'
> D [05/Sep/2014:15:56:18 +0200] [Job 340] %%Pages: 1
> D [05/Sep/2014:15:56:18 +0200] [Job 340] %%EndComments
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Before copy_prolog - %%BeginProlog
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Before copy_setup - %%Page: 1 1
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Before page loop - %%Page: 1 1
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Copying page 1...
> D [05/Sep/2014:15:56:18 +0200] [Job 340] pagew = 566.9, pagel = 813.5
> D [05/Sep/2014:15:56:18 +0200] [Job 340] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
> D [05/Sep/2014:15:56:18 +0200] [Job 340] PageLeft = 14.2, PageRight = 581.1
> D [05/Sep/2014:15:56:18 +0200] [Job 340] PageTop = 827.8, PageBottom = 14.3
> D [05/Sep/2014:15:56:18 +0200] [Job 340] PageWidth = 595.0, PageLength = 842.0
> D [05/Sep/2014:15:56:18 +0200] [Job 340] opvpOpenPrinter(463)
> D [05/Sep/2014:15:56:18 +0200] [Job 340] CStubOpenPrinter(397)
> D [05/Sep/2014:15:56:18 +0200] [Job 340] opvpOpenPrinter(463)
> D [05/Sep/2014:15:56:18 +0200] [Job 340] CStubOpenPrinter(397)
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Connecting to 192.168.2.202:9100
> I [05/Sep/2014:15:56:18 +0200] [Job 340] Connecting to printer.
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Set job-printer-state-message to "Connecting to printer.", current level=INFO
> D [05/Sep/2014:15:56:18 +0200] Discarding unused job-progress event...
> D [05/Sep/2014:15:56:18 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:18 +0200] [Job 340] STATE: -connecting-to-device
> D [05/Sep/2014:15:56:18 +0200] Discarding unused printer-state-changed event...
> I [05/Sep/2014:15:56:18 +0200] [Job 340] Connected to printer.
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Set job-printer-state-message to "Connected to printer.", current level=INFO
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Connected to 192.168.2.202:9100...
> D [05/Sep/2014:15:56:18 +0200] [Job 340] backendRunLoop(print_fd=0, device_fd=6, snmp_fd=5, addr=0xf7acb97c, use_bc=1, side_cb=0xf76fe7b0)
> D [05/Sep/2014:15:56:18 +0200] [Job 340] Read 753 bytes of print data...
> D [05/Sep/2014:15:56:18 +0200] Discarding unused job-progress event...
> D [05/Sep/2014:15:56:18 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:18 +0200] [Job 340] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:56:18 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:18 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
> D [05/Sep/2014:15:56:18 +0200] [Job 340] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:56:18 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:18 +0200] [Job 340] new_state=0, change_state=0
> D [05/Sep/2014:15:56:19 +0200] [Job 340] Wrote 753 bytes of print data...
> D [05/Sep/2014:15:56:24 +0200] [Job 340] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:56:24 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:24 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:56:24 +0200] [Job 340] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:56:24 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:24 +0200] [Job 340] new_state=0, change_state=0
> D [05/Sep/2014:15:56:29 +0200] [Job 340] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:56:29 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:29 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:56:29 +0200] [Job 340] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:56:29 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:29 +0200] [Job 340] new_state=0, change_state=0
> D [05/Sep/2014:15:56:34 +0200] [Job 340] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:56:34 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:34 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:56:34 +0200] [Job 340] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:56:34 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:34 +0200] [Job 340] new_state=0, change_state=0
> D [05/Sep/2014:15:56:39 +0200] [Job 340] ATTR: marker-levels=100,100,100,100
> D [05/Sep/2014:15:56:39 +0200] cupsdMarkDirty(P-----)
> D [05/Sep/2014:15:56:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
> D [05/Sep/2014:15:56:39 +0200] [Job 340] new_supply_state=0, change_state=0
> D [05/Sep/2014:15:56:39 +0200] Discarding unused printer-state-changed event...
> D [05/Sep/2014:15:56:39 +0200] [Job 340] new_state=0, change_state=0
> D [05/Sep/2014:15:56:41 +0200] [Job 340] Wrote 1 pages...
> D [05/Sep/2014:15:56:41 +0200] [Job 340] PID 25916 (pstops) exited with no errors.
> D [05/Sep/2014:15:56:41 +0200] [Job 340] PID 25915 (gs) exited with no errors.
> D [05/Sep/2014:15:56:41 +0200] PID 25912 (/usr/lib/cups/filter/pdftops) exited with no errors.
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 38 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 38 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Read 8192 bytes of print data...
> D [05/Sep/2014:15:56:42 +0200] [Job 340] Wrote 8192 bytes of print data...
> .... And this goes on ....
> -----------
>
> --
> Håkon Alstadheim / N-7510 Skatval / email: hakon at alstadheim.priv.no
> tlf: 74 82 60 27 mob: 47 35 39 38
> http://alstadheim.priv.no/hakon/
>
>
> _______________________________________________
> cups mailing list
> cups at cups.org
> https://www.cups.org/mailman/listinfo/cups
More information about the cups
mailing list