I [27/May/2012:07:53:07 +0100] Remote access is disabled.
D [27/May/2012:07:53:07 +0100] Added auto ServerAlias al-desktop
I [27/May/2012:07:53:07 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/May/2012:07:53:07 +0100] Using default TempDir of /var/spool/cups/tmp...
I [27/May/2012:07:53:07 +0100] Configured for up to 100 clients.
I [27/May/2012:07:53:07 +0100] Allowing up to 100 client connections per host.
I [27/May/2012:07:53:07 +0100] Using policy "default" as the default.
I [27/May/2012:07:53:07 +0100] Full reload is required.
D [27/May/2012:07:53:07 +0100] cupsdMarkDirty(----J-)
D [27/May/2012:07:53:07 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
D [27/May/2012:07:53:07 +0100] Discarding unused printer-state-changed event...
D [27/May/2012:07:53:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
I [27/May/2012:07:53:07 +0100] Saving job.cache...
D [27/May/2012:07:53:07 +0100] [Job 62] Unloading...
D [27/May/2012:07:53:07 +0100] Discarding unused printer-stopped event...
D [27/May/2012:07:53:07 +0100] cupsdMarkDirty(P-----)
D [27/May/2012:07:53:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/May/2012:07:53:07 +0100] Calling DeleteDevice(cups-LaserJet-4L)
D [27/May/2012:07:53:07 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [27/May/2012:07:53:07 +0100] cupsdDeregisterPrinter(p=0x7fa340ce6630(LaserJet-4L), removeit=1)
I [27/May/2012:07:53:07 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 55 filters...
D [27/May/2012:07:53:07 +0100] Loading printer LaserJet-4L...
D [27/May/2012:07:53:07 +0100] load_ppd: Loading /var/cache/cups/LaserJet-4L.data...
D [27/May/2012:07:53:07 +0100] Calling DeleteDevice(cups-LaserJet-4L)
D [27/May/2012:07:53:07 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [27/May/2012:07:53:07 +0100] Using profile id of LaserJet-4L-Gray..
D [27/May/2012:07:53:07 +0100] Calling CreateProfile(LaserJet-4L-Gray..,temp)
W [27/May/2012:07:53:07 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'LaserJet-4L-Gray..' already exists
I [27/May/2012:07:53:07 +0100] Registering ICC color profiles for "LaserJet-4L"
D [27/May/2012:07:53:07 +0100] Calling CreateDevice(cups-LaserJet-4L,temp)
W [27/May/2012:07:53:07 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-LaserJet-4L' already exists
D [27/May/2012:07:53:07 +0100] cupsdRegisterPrinter(p=0x7fa340d48ea0(LaserJet-4L))
D [27/May/2012:07:53:07 +0100] cupsdLoadRemoteCache: Not loading remote cache.
I [27/May/2012:07:53:07 +0100] Loading job cache file "/var/cache/cups/job.cache"...
D [27/May/2012:07:53:07 +0100] [Job 61] Loading from cache...
D [27/May/2012:07:53:07 +0100] [Job 62] Loading from cache...
D [27/May/2012:07:53:07 +0100] [Job 62] Loading attributes...
D [27/May/2012:07:53:07 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [27/May/2012:07:53:07 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [27/May/2012:07:53:07 +0100] Full reload complete.
I [27/May/2012:07:53:07 +0100] Listening to [v1.::1]:631 on fd 9...
I [27/May/2012:07:53:07 +0100] Listening to 127.0.0.1:631 on fd 10...
I [27/May/2012:07:53:07 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [27/May/2012:07:53:07 +0100] Resuming new connection processing...
D [27/May/2012:07:53:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/May/2012:07:53:07 +0100] Notifier dbus started - PID = 15703
D [27/May/2012:07:53:07 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/May/2012:07:53:07 +0100] PID 3075 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [27/May/2012:07:53:07 +0100] PID 3117 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [27/May/2012:07:53:07 +0100] PID 3138 (/usr/lib/cups/backend/usb) was terminated normally with signal 9.
D [27/May/2012:07:53:07 +0100] [Notifier] state=3
D [27/May/2012:07:53:07 +0100] [Notifier] Connected to D-BUS
D [27/May/2012:07:53:19 +0100] cupsdMarkDirty(----J-)
D [27/May/2012:07:53:19 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/May/2012:07:53:19 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/May/2012:07:53:19 +0100] Notifier dbus started - PID = 15704
D [27/May/2012:07:53:19 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:19 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/May/2012:07:53:19 +0100] [Job 62] job-sheets=none,none
D [27/May/2012:07:53:19 +0100] [Job 62] argv[0]="LaserJet-4L"
D [27/May/2012:07:53:19 +0100] [Job 62] argv[1]="62"
D [27/May/2012:07:53:19 +0100] [Job 62] argv[2]="al"
D [27/May/2012:07:53:19 +0100] [Job 62] argv[3]="Untitled Document 1"
D [27/May/2012:07:53:19 +0100] [Job 62] argv[4]="1"
D [27/May/2012:07:53:19 +0100] [Job 62] argv[5]="InputSlot=Default TonerDensity=3 PageSize=A4 number-up=1 REt=Medium Manualfeed=Off Economode=Off Resolution=300x300dpi job-uuid=urn:uuid:e52a2ad3-66e3-31e0-5161-998d92279657 job-originating-host-name=localhost time-at-creation=1338030712 time-at-processing=1338101599"
D [27/May/2012:07:53:19 +0100] [Job 62] argv[6]="/var/spool/cups/d00062-001"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[8]="HOME=/var/spool/cups/tmp"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[10]="SERVER_ADMIN=root@al-desktop"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[11]="SOFTWARE=CUPS/1.5.3"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[13]="USER=root"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[16]="IPP_PORT=631"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[17]="CHARSET=utf-8"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[18]="LANG=en_GB.UTF-8"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[19]="PPD=/etc/cups/ppd/LaserJet-4L.ppd"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[20]="RIP_MAX_CACHE=128m"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[21]="CONTENT_TYPE=application/pdf"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[22]="DEVICE_URI=usb://HP/LaserJet%204L"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[23]="PRINTER_INFO=Hewlett-Packard LaserJet 4L"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[24]="PRINTER_LOCATION=al-desktop"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[25]="PRINTER=LaserJet-4L"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[26]="PRINTER_STATE_REASONS=none"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[27]="CUPS_FILETYPE=document"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[28]="FINAL_CONTENT_TYPE=printer/LaserJet-4L"
D [27/May/2012:07:53:19 +0100] [Job 62] envp[29]="AUTH_I****"
I [27/May/2012:07:53:19 +0100] [Job 62] Started filter /usr/lib/cups/filter/pdftopdf (PID 15705)
I [27/May/2012:07:53:19 +0100] [Job 62] Started filter /usr/lib/cups/filter/foomatic-rip (PID 15706)
I [27/May/2012:07:53:19 +0100] [Job 62] Started backend /usr/lib/cups/backend/usb (PID 15707)
D [27/May/2012:07:53:19 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:19 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [27/May/2012:07:53:19 +0100] [Notifier] state=3
D [27/May/2012:07:53:19 +0100] [Notifier] state=3
D [27/May/2012:07:53:19 +0100] [Notifier] state=3
D [27/May/2012:07:53:19 +0100] [Job 62] Getting input from file 
D [27/May/2012:07:53:19 +0100] [Job 62] foomatic-rip version 4.0.15.251 running...
D [27/May/2012:07:53:19 +0100] [Job 62] Parsing PPD file ...
D [27/May/2012:07:53:19 +0100] [Job 62] Added option ColorSpace
D [27/May/2012:07:53:19 +0100] [Job 62] Added option PageSize
D [27/May/2012:07:53:19 +0100] [Notifier] Connected to D-BUS
D [27/May/2012:07:53:19 +0100] [Notifier] state=3
D [27/May/2012:07:53:19 +0100] [Job 62] Added option ImageableArea
D [27/May/2012:07:53:19 +0100] [Job 62] Added option PaperDimension
D [27/May/2012:07:53:19 +0100] [Job 62] Added option InputSlot
D [27/May/2012:07:53:19 +0100] [Job 62] Added option Manualfeed
D [27/May/2012:07:53:19 +0100] [Job 62] Added option Economode
D [27/May/2012:07:53:19 +0100] [Job 62] Added option Copies
D [27/May/2012:07:53:19 +0100] [Job 62] libusb_get_device_list=18
D [27/May/2012:07:53:19 +0100] [Job 62] Added option Resolution
D [27/May/2012:07:53:19 +0100] [Job 62] Added option REt
D [27/May/2012:07:53:19 +0100] [Job 62] Added option TonerDensity
D [27/May/2012:07:53:19 +0100] [Job 62] Added option Font
D [27/May/2012:07:53:19 +0100] [Job 62] 
D [27/May/2012:07:53:19 +0100] [Job 62] Parameter Summary
D [27/May/2012:07:53:19 +0100] [Job 62] -----------------
D [27/May/2012:07:53:19 +0100] [Job 62] 
D [27/May/2012:07:53:19 +0100] [Job 62] Spooler: cups
D [27/May/2012:07:53:19 +0100] [Job 62] Printer: LaserJet-4L
D [27/May/2012:07:53:19 +0100] [Job 62] Shell: /bin/bash
D [27/May/2012:07:53:19 +0100] [Job 62] PPD file: /etc/cups/ppd/LaserJet-4L.ppd
D [27/May/2012:07:53:19 +0100] [Job 62] ATTR file: 
D [27/May/2012:07:53:19 +0100] [Job 62] Printer model: HP LaserJet 4L Foomatic/ljet4 (recommended)
D [27/May/2012:07:53:19 +0100] [Job 62] Job title: Untitled Document 1
D [27/May/2012:07:53:19 +0100] [Job 62] File(s) to be printed:
D [27/May/2012:07:53:19 +0100] [Job 62] <STDIN>
D [27/May/2012:07:53:19 +0100] [Job 62] 
D [27/May/2012:07:53:19 +0100] [Job 62] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [27/May/2012:07:53:19 +0100] [Job 62] Printing system options:
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'number-up=1'
D [27/May/2012:07:53:19 +0100] [Job 62] Unknown option number-up=1.
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'job-uuid=urn:uuid:e52a2ad3-66e3-31e0-5161-998d92279657'
D [27/May/2012:07:53:19 +0100] [Job 62] Unknown option job-uuid=urn:uuid:e52a2ad3-66e3-31e0-5161-998d92279657.
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'job-originating-host-name=localhost'
D [27/May/2012:07:53:19 +0100] [Job 62] Unknown option job-originating-host-name=localhost.
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'time-at-creation=1338030712'
D [27/May/2012:07:53:19 +0100] [Job 62] Unknown option time-at-creation=1338030712.
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'time-at-processing=133810159'
D [27/May/2012:07:53:19 +0100] [Job 62] Unknown option time-at-processing=133810159.
D [27/May/2012:07:53:19 +0100] [Job 62] Options from the PPD file:
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'InputSlot=Default'
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'TonerDensity=3'
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'PageSize=A4'
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'REt=Medium'
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'Manualfeed=Off'
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'Economode=Off'
D [27/May/2012:07:53:19 +0100] [Job 62] Pondering option 'Resolution=300x300dpi'
D [27/May/2012:07:53:19 +0100] [Job 62] 
D [27/May/2012:07:53:19 +0100] [Job 62] ================================================
D [27/May/2012:07:53:19 +0100] [Job 62] 
D [27/May/2012:07:53:19 +0100] [Job 62] File: <STDIN>
D [27/May/2012:07:53:19 +0100] [Job 62] 
D [27/May/2012:07:53:19 +0100] [Job 62] ================================================
D [27/May/2012:07:53:19 +0100] [Job 62] 
D [27/May/2012:07:53:19 +0100] [Job 62] Filetype: PDF
D [27/May/2012:07:53:19 +0100] [Job 62] Storing temporary files in /var/spool/cups/tmp
D [27/May/2012:07:53:19 +0100] PID 15705 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/May/2012:07:53:19 +0100] [Job 62] STATE: +connecting-to-device
D [27/May/2012:07:53:19 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:19 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [27/May/2012:07:53:19 +0100] [Notifier] state=3
D [27/May/2012:07:53:19 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Job 62] STATE: -connecting-to-device
D [27/May/2012:07:53:20 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:20 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
I [27/May/2012:07:53:20 +0100] [Job 62] Sending data to printer.
D [27/May/2012:07:53:20 +0100] [Job 62] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [27/May/2012:07:53:20 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:20 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/May/2012:07:53:20 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:20 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Job 62] File contains 1 pages
D [27/May/2012:07:53:20 +0100] [Job 62] Starting renderer with command: gs -dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -dNOINTERPOLATE -sDEVICE=ljet4 -dMediaPosition=0 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -r300x300 -sOutputFile=- -f   /var/spool/cups/tmp/foomatic-TR9QV8 
D [27/May/2012:07:53:20 +0100] [Job 62] Starting process "kid3" (generation 1)
D [27/May/2012:07:53:20 +0100] [Job 62] Starting process "kid4" (generation 2)
D [27/May/2012:07:53:20 +0100] [Job 62] Starting process "renderer" (generation 2)
D [27/May/2012:07:53:20 +0100] [Job 62] JCL: %-12345X@PJL
D [27/May/2012:07:53:20 +0100] [Job 62] @PJL SET DENSITY=3
D [27/May/2012:07:53:20 +0100] [Job 62] @PJL SET RET=MEDIUM
D [27/May/2012:07:53:20 +0100] [Job 62] @PJL SET COPIES=1
D [27/May/2012:07:53:20 +0100] [Job 62] @PJL SET ECONOMODE=OFF
D [27/May/2012:07:53:20 +0100] [Job 62] @PJL SET MANUALFEED=OFF
D [27/May/2012:07:53:20 +0100] [Job 62] <job data> %-12345X@PJL RESET
D [27/May/2012:07:53:20 +0100] [Job 62] 
D [27/May/2012:07:53:20 +0100] [Job 62] 
D [27/May/2012:07:53:20 +0100] [Job 62] renderer exited with status 0
D [27/May/2012:07:53:20 +0100] [Job 62] Read 1982 bytes of print data...
D [27/May/2012:07:53:20 +0100] [Job 62] kid4 exited with status 0
D [27/May/2012:07:53:20 +0100] [Job 62] kid3 finished
D [27/May/2012:07:53:20 +0100] [Job 62] Kid3 exit status: 0
D [27/May/2012:07:53:20 +0100] [Job 62] 
D [27/May/2012:07:53:20 +0100] [Job 62] Closing foomatic-rip.
D [27/May/2012:07:53:20 +0100] PID 15706 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [27/May/2012:07:53:20 +0100] [Job 62] Wrote 1982 bytes of print data...
D [27/May/2012:07:53:20 +0100] [Job 62] Sent 1982 bytes...
D [27/May/2012:07:53:20 +0100] [Job 62] Waiting for read thread to exit...
D [27/May/2012:07:53:20 +0100] [Job 62] Read 57 bytes of back-channel data...
D [27/May/2012:07:53:20 +0100] PID 15707 (/usr/lib/cups/backend/usb) exited with no errors.
D [27/May/2012:07:53:20 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:20 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [27/May/2012:07:53:20 +0100] [Job 62] Job completed.
D [27/May/2012:07:53:20 +0100] cupsdMarkDirty(----J-)
D [27/May/2012:07:53:20 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/May/2012:07:53:20 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/May/2012:07:53:20 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:53:20 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
D [27/May/2012:07:53:20 +0100] [Notifier] state=3
I [27/May/2012:07:53:38 +0100] Saving printers.conf...
I [27/May/2012:07:53:39 +0100] Saving job.cache...
I [27/May/2012:07:53:39 +0100] Saving subscriptions.conf...
D [27/May/2012:07:53:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
D [27/May/2012:07:53:39 +0100] Report: clients=0
D [27/May/2012:07:53:39 +0100] Report: jobs=2
D [27/May/2012:07:53:39 +0100] Report: jobs-active=0
D [27/May/2012:07:53:39 +0100] Report: printers=1
D [27/May/2012:07:53:39 +0100] Report: printers-implicit=0
D [27/May/2012:07:53:39 +0100] Report: stringpool-string-count=2263
D [27/May/2012:07:53:39 +0100] Report: stringpool-alloc-bytes=10464
D [27/May/2012:07:53:39 +0100] Report: stringpool-total-bytes=40920
D [27/May/2012:07:59:13 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:07:59:13 +0100] [Job 62] Unloading...
D [27/May/2012:07:59:13 +0100] Report: clients=1
D [27/May/2012:07:59:13 +0100] Report: jobs=2
D [27/May/2012:07:59:13 +0100] Report: jobs-active=0
D [27/May/2012:07:59:13 +0100] Report: printers=1
D [27/May/2012:07:59:13 +0100] Report: printers-implicit=0
D [27/May/2012:07:59:13 +0100] Report: stringpool-string-count=2213
D [27/May/2012:07:59:13 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:07:59:13 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:07:59:13 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:07:59:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:07:59:13 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:07:59:13 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:07:59:13 +0100] Renew-Subscription /
D [27/May/2012:07:59:13 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:07:59:13 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:07:59:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:07:59:13 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:07:59:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:07:59:45 +0100] Saving subscriptions.conf...
D [27/May/2012:07:59:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:08:02:04 +0100] Report: clients=1
D [27/May/2012:08:02:04 +0100] Report: jobs=2
D [27/May/2012:08:02:04 +0100] Report: jobs-active=0
D [27/May/2012:08:02:04 +0100] Report: printers=1
D [27/May/2012:08:02:04 +0100] Report: printers-implicit=0
D [27/May/2012:08:02:04 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:02:04 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:02:04 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:04:14 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:08:04:14 +0100] cupsdCloseClient: 16
D [27/May/2012:08:04:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:08:04:14 +0100] Report: clients=0
D [27/May/2012:08:04:14 +0100] Report: jobs=2
D [27/May/2012:08:04:14 +0100] Report: jobs-active=0
D [27/May/2012:08:04:14 +0100] Report: printers=1
D [27/May/2012:08:04:14 +0100] Report: printers-implicit=0
D [27/May/2012:08:04:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:04:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:04:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:13:13 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:08:13:13 +0100] Report: clients=1
D [27/May/2012:08:13:13 +0100] Report: jobs=2
D [27/May/2012:08:13:13 +0100] Report: jobs-active=0
D [27/May/2012:08:13:13 +0100] Report: printers=1
D [27/May/2012:08:13:13 +0100] Report: printers-implicit=0
D [27/May/2012:08:13:13 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:13:13 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:13:13 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:13:13 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:08:13:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:08:13:13 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:08:13:13 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:08:13:13 +0100] Renew-Subscription /
D [27/May/2012:08:13:13 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:08:13:13 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:08:13:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:08:13:13 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:08:13:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:08:13:44 +0100] Saving subscriptions.conf...
D [27/May/2012:08:13:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:08:18:14 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:08:18:14 +0100] cupsdCloseClient: 16
D [27/May/2012:08:18:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:08:18:14 +0100] Report: clients=0
D [27/May/2012:08:18:14 +0100] Report: jobs=2
D [27/May/2012:08:18:14 +0100] Report: jobs-active=0
D [27/May/2012:08:18:14 +0100] Report: printers=1
D [27/May/2012:08:18:14 +0100] Report: printers-implicit=0
D [27/May/2012:08:18:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:18:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:18:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:27:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:08:27:14 +0100] Report: clients=1
D [27/May/2012:08:27:14 +0100] Report: jobs=2
D [27/May/2012:08:27:14 +0100] Report: jobs-active=0
D [27/May/2012:08:27:14 +0100] Report: printers=1
D [27/May/2012:08:27:14 +0100] Report: printers-implicit=0
D [27/May/2012:08:27:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:27:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:27:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:27:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:08:27:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:08:27:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:08:27:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:08:27:14 +0100] Renew-Subscription /
D [27/May/2012:08:27:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:08:27:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:08:27:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:08:27:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:08:27:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:08:27:45 +0100] Saving subscriptions.conf...
D [27/May/2012:08:27:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:08:32:15 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:08:32:15 +0100] cupsdCloseClient: 16
D [27/May/2012:08:32:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:08:32:15 +0100] Report: clients=0
D [27/May/2012:08:32:15 +0100] Report: jobs=2
D [27/May/2012:08:32:15 +0100] Report: jobs-active=0
D [27/May/2012:08:32:15 +0100] Report: printers=1
D [27/May/2012:08:32:15 +0100] Report: printers-implicit=0
D [27/May/2012:08:32:15 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:32:15 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:32:15 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:33:35 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:08:33:35 +0100] Report: clients=1
D [27/May/2012:08:33:35 +0100] Report: jobs=2
D [27/May/2012:08:33:35 +0100] Report: jobs-active=0
D [27/May/2012:08:33:35 +0100] Report: printers=1
D [27/May/2012:08:33:35 +0100] Report: printers-implicit=0
D [27/May/2012:08:33:35 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:33:35 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:33:35 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:33:35 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:08:33:35 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:08:33:35 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:08:33:35 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:08:33:35 +0100] Renew-Subscription /
D [27/May/2012:08:33:35 +0100] cupsdIsAuthorized: requesting-user-name="al"
D [27/May/2012:08:33:35 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:08:33:35 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:08:33:35 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:08:33:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/May/2012:08:33:35 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [27/May/2012:08:33:35 +0100] cupsdCloseClient: 16
D [27/May/2012:08:33:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [27/May/2012:08:34:07 +0100] Saving subscriptions.conf...
D [27/May/2012:08:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:08:41:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:08:41:14 +0100] Report: clients=1
D [27/May/2012:08:41:14 +0100] Report: jobs=2
D [27/May/2012:08:41:14 +0100] Report: jobs-active=0
D [27/May/2012:08:41:14 +0100] Report: printers=1
D [27/May/2012:08:41:14 +0100] Report: printers-implicit=0
D [27/May/2012:08:41:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:41:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:41:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:41:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:08:41:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:08:41:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:08:41:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:08:41:14 +0100] Renew-Subscription /
D [27/May/2012:08:41:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:08:41:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:08:41:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:08:41:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:08:41:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:08:41:45 +0100] Saving subscriptions.conf...
D [27/May/2012:08:41:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:08:46:15 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:08:46:15 +0100] cupsdCloseClient: 16
D [27/May/2012:08:46:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:08:46:15 +0100] Report: clients=0
D [27/May/2012:08:46:15 +0100] Report: jobs=2
D [27/May/2012:08:46:15 +0100] Report: jobs-active=0
D [27/May/2012:08:46:15 +0100] Report: printers=1
D [27/May/2012:08:46:15 +0100] Report: printers-implicit=0
D [27/May/2012:08:46:15 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:46:15 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:46:15 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:55:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:08:55:14 +0100] Report: clients=1
D [27/May/2012:08:55:14 +0100] Report: jobs=2
D [27/May/2012:08:55:14 +0100] Report: jobs-active=0
D [27/May/2012:08:55:14 +0100] Report: printers=1
D [27/May/2012:08:55:14 +0100] Report: printers-implicit=0
D [27/May/2012:08:55:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:08:55:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:08:55:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:08:55:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:08:55:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:08:55:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:08:55:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:08:55:14 +0100] Renew-Subscription /
D [27/May/2012:08:55:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:08:55:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:08:55:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:08:55:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:08:55:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:08:55:45 +0100] Saving subscriptions.conf...
D [27/May/2012:08:55:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:09:00:14 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:09:00:14 +0100] cupsdCloseClient: 16
D [27/May/2012:09:00:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:00:14 +0100] Report: clients=0
D [27/May/2012:09:00:14 +0100] Report: jobs=2
D [27/May/2012:09:00:14 +0100] Report: jobs-active=0
D [27/May/2012:09:00:14 +0100] Report: printers=1
D [27/May/2012:09:00:14 +0100] Report: printers-implicit=0
D [27/May/2012:09:00:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:00:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:00:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:09:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:09:09:14 +0100] Report: clients=1
D [27/May/2012:09:09:14 +0100] Report: jobs=2
D [27/May/2012:09:09:14 +0100] Report: jobs-active=0
D [27/May/2012:09:09:14 +0100] Report: printers=1
D [27/May/2012:09:09:14 +0100] Report: printers-implicit=0
D [27/May/2012:09:09:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:09:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:09:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:09:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:09:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:09:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:09:09:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:09:09:14 +0100] Renew-Subscription /
D [27/May/2012:09:09:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:09:09:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:09:09:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:09:09:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:09:09:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:09:09:45 +0100] Saving subscriptions.conf...
D [27/May/2012:09:09:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:09:14:14 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:09:14:14 +0100] cupsdCloseClient: 16
D [27/May/2012:09:14:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:14:14 +0100] Report: clients=0
D [27/May/2012:09:14:14 +0100] Report: jobs=2
D [27/May/2012:09:14:14 +0100] Report: jobs-active=0
D [27/May/2012:09:14:14 +0100] Report: printers=1
D [27/May/2012:09:14:14 +0100] Report: printers-implicit=0
D [27/May/2012:09:14:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:14:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:14:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:23:13 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:09:23:13 +0100] Report: clients=1
D [27/May/2012:09:23:13 +0100] Report: jobs=2
D [27/May/2012:09:23:13 +0100] Report: jobs-active=0
D [27/May/2012:09:23:13 +0100] Report: printers=1
D [27/May/2012:09:23:13 +0100] Report: printers-implicit=0
D [27/May/2012:09:23:13 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:23:13 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:23:13 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:23:13 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:23:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:23:13 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:09:23:13 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:09:23:13 +0100] Renew-Subscription /
D [27/May/2012:09:23:13 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:09:23:13 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:09:23:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:09:23:13 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:09:23:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:09:23:45 +0100] Saving subscriptions.conf...
D [27/May/2012:09:23:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:09:28:14 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:09:28:14 +0100] cupsdCloseClient: 16
D [27/May/2012:09:28:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:28:14 +0100] Report: clients=0
D [27/May/2012:09:28:14 +0100] Report: jobs=2
D [27/May/2012:09:28:14 +0100] Report: jobs-active=0
D [27/May/2012:09:28:14 +0100] Report: printers=1
D [27/May/2012:09:28:14 +0100] Report: printers-implicit=0
D [27/May/2012:09:28:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:28:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:28:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:31:55 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:09:31:55 +0100] Report: clients=1
D [27/May/2012:09:31:55 +0100] Report: jobs=2
D [27/May/2012:09:31:55 +0100] Report: jobs-active=0
D [27/May/2012:09:31:55 +0100] Report: printers=1
D [27/May/2012:09:31:55 +0100] Report: printers-implicit=0
D [27/May/2012:09:31:55 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:31:55 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:31:55 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:31:55 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:31:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:31:55 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:09:31:55 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:09:31:55 +0100] Renew-Subscription /
D [27/May/2012:09:31:55 +0100] cupsdIsAuthorized: requesting-user-name="al"
D [27/May/2012:09:31:55 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:09:31:55 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:09:31:55 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:09:31:55 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/May/2012:09:31:55 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [27/May/2012:09:31:55 +0100] cupsdCloseClient: 16
D [27/May/2012:09:31:55 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [27/May/2012:09:32:27 +0100] Saving subscriptions.conf...
D [27/May/2012:09:32:27 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:09:35:56 +0100] Report: clients=0
D [27/May/2012:09:35:56 +0100] Report: jobs=2
D [27/May/2012:09:35:56 +0100] Report: jobs-active=0
D [27/May/2012:09:35:56 +0100] Report: printers=1
D [27/May/2012:09:35:56 +0100] Report: printers-implicit=0
D [27/May/2012:09:35:56 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:35:56 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:35:56 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:35:58 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:09:35:58 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:35:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:35:58 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:09:35:58 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [27/May/2012:09:35:58 +0100] CUPS-Get-Printers
D [27/May/2012:09:35:58 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [27/May/2012:09:35:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/May/2012:09:35:58 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:35:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:35:58 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:09:35:58 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [27/May/2012:09:35:58 +0100] CUPS-Get-Default
D [27/May/2012:09:35:58 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [27/May/2012:09:35:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/May/2012:09:35:58 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [27/May/2012:09:35:58 +0100] cupsdCloseClient: 16
D [27/May/2012:09:35:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:36:01 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:09:36:01 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:36:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:36:01 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:09:36:01 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [27/May/2012:09:36:01 +0100] CUPS-Get-Printers
D [27/May/2012:09:36:01 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [27/May/2012:09:36:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/May/2012:09:36:01 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:36:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:36:01 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:09:36:01 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [27/May/2012:09:36:01 +0100] CUPS-Get-Default
D [27/May/2012:09:36:01 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [27/May/2012:09:36:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/May/2012:09:36:06 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [27/May/2012:09:36:06 +0100] cupsdCloseClient: 16
D [27/May/2012:09:36:06 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:36:21 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:09:36:21 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:36:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:36:21 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:09:36:21 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [27/May/2012:09:36:21 +0100] CUPS-Get-Printers
D [27/May/2012:09:36:21 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [27/May/2012:09:36:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/May/2012:09:36:21 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:36:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:36:21 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:09:36:21 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [27/May/2012:09:36:21 +0100] CUPS-Get-Default
D [27/May/2012:09:36:21 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [27/May/2012:09:36:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/May/2012:09:37:14 +0100] cupsdAcceptClient: 20 from localhost (Domain)
D [27/May/2012:09:37:14 +0100] Report: clients=2
D [27/May/2012:09:37:14 +0100] Report: jobs=2
D [27/May/2012:09:37:14 +0100] Report: jobs-active=0
D [27/May/2012:09:37:14 +0100] Report: printers=1
D [27/May/2012:09:37:14 +0100] Report: printers-implicit=0
D [27/May/2012:09:37:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:37:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:37:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:37:14 +0100] cupsdReadClient: 20 POST / HTTP/1.1
D [27/May/2012:09:37:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:37:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:09:37:14 +0100] cupsdReadClient: 20 1.1 Renew-Subscription 1
D [27/May/2012:09:37:14 +0100] Renew-Subscription /
D [27/May/2012:09:37:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:09:37:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:09:37:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:09:37:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:09:37:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:09:37:45 +0100] Saving subscriptions.conf...
D [27/May/2012:09:37:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:09:41:22 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:09:41:22 +0100] cupsdCloseClient: 16
D [27/May/2012:09:41:22 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:41:22 +0100] Report: clients=1
D [27/May/2012:09:41:22 +0100] Report: jobs=2
D [27/May/2012:09:41:22 +0100] Report: jobs-active=0
D [27/May/2012:09:41:22 +0100] Report: printers=1
D [27/May/2012:09:41:22 +0100] Report: printers-implicit=0
D [27/May/2012:09:41:22 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:41:22 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:41:22 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:42:15 +0100] Closing client 20 after 300 seconds of inactivity...
D [27/May/2012:09:42:15 +0100] cupsdCloseClient: 20
D [27/May/2012:09:42:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:51:13 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:09:51:13 +0100] Report: clients=1
D [27/May/2012:09:51:13 +0100] Report: jobs=2
D [27/May/2012:09:51:13 +0100] Report: jobs-active=0
D [27/May/2012:09:51:13 +0100] Report: printers=1
D [27/May/2012:09:51:13 +0100] Report: printers-implicit=0
D [27/May/2012:09:51:13 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:51:13 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:51:13 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:51:13 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:09:51:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:09:51:13 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:09:51:13 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:09:51:13 +0100] Renew-Subscription /
D [27/May/2012:09:51:13 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:09:51:13 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:09:51:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:09:51:13 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:09:51:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:09:51:45 +0100] Saving subscriptions.conf...
D [27/May/2012:09:51:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:09:56:14 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:09:56:14 +0100] cupsdCloseClient: 16
D [27/May/2012:09:56:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:09:56:14 +0100] Report: clients=0
D [27/May/2012:09:56:14 +0100] Report: jobs=2
D [27/May/2012:09:56:14 +0100] Report: jobs-active=0
D [27/May/2012:09:56:14 +0100] Report: printers=1
D [27/May/2012:09:56:14 +0100] Report: printers-implicit=0
D [27/May/2012:09:56:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:56:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:56:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:09:59:00 +0100] Report: clients=0
D [27/May/2012:09:59:00 +0100] Report: jobs=2
D [27/May/2012:09:59:00 +0100] Report: jobs-active=0
D [27/May/2012:09:59:00 +0100] Report: printers=1
D [27/May/2012:09:59:00 +0100] Report: printers-implicit=0
D [27/May/2012:09:59:00 +0100] Report: stringpool-string-count=2213
D [27/May/2012:09:59:00 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:09:59:00 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:05:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:10:05:14 +0100] Report: clients=1
D [27/May/2012:10:05:14 +0100] Report: jobs=2
D [27/May/2012:10:05:14 +0100] Report: jobs-active=0
D [27/May/2012:10:05:14 +0100] Report: printers=1
D [27/May/2012:10:05:14 +0100] Report: printers-implicit=0
D [27/May/2012:10:05:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:05:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:05:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:05:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:10:05:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:10:05:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:10:05:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:10:05:14 +0100] Renew-Subscription /
D [27/May/2012:10:05:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:10:05:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:10:05:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:10:05:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:10:05:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:10:05:45 +0100] Saving subscriptions.conf...
D [27/May/2012:10:05:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:10:10:15 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:10:10:15 +0100] cupsdCloseClient: 16
D [27/May/2012:10:10:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:10:10:15 +0100] Report: clients=0
D [27/May/2012:10:10:15 +0100] Report: jobs=2
D [27/May/2012:10:10:15 +0100] Report: jobs-active=0
D [27/May/2012:10:10:15 +0100] Report: printers=1
D [27/May/2012:10:10:15 +0100] Report: printers-implicit=0
D [27/May/2012:10:10:15 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:10:15 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:10:15 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:19:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:10:19:14 +0100] Report: clients=1
D [27/May/2012:10:19:14 +0100] Report: jobs=2
D [27/May/2012:10:19:14 +0100] Report: jobs-active=0
D [27/May/2012:10:19:14 +0100] Report: printers=1
D [27/May/2012:10:19:14 +0100] Report: printers-implicit=0
D [27/May/2012:10:19:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:19:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:19:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:19:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:10:19:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:10:19:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:10:19:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:10:19:14 +0100] Renew-Subscription /
D [27/May/2012:10:19:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:10:19:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:10:19:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:10:19:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:10:19:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:10:19:45 +0100] Saving subscriptions.conf...
D [27/May/2012:10:19:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:10:22:48 +0100] Report: clients=1
D [27/May/2012:10:22:48 +0100] Report: jobs=2
D [27/May/2012:10:22:48 +0100] Report: jobs-active=0
D [27/May/2012:10:22:48 +0100] Report: printers=1
D [27/May/2012:10:22:48 +0100] Report: printers-implicit=0
D [27/May/2012:10:22:48 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:22:48 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:22:48 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:24:15 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:10:24:15 +0100] cupsdCloseClient: 16
D [27/May/2012:10:24:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:10:24:15 +0100] Report: clients=0
D [27/May/2012:10:24:15 +0100] Report: jobs=2
D [27/May/2012:10:24:15 +0100] Report: jobs-active=0
D [27/May/2012:10:24:15 +0100] Report: printers=1
D [27/May/2012:10:24:15 +0100] Report: printers-implicit=0
D [27/May/2012:10:24:15 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:24:15 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:24:15 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:30:15 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:10:30:15 +0100] Report: clients=1
D [27/May/2012:10:30:15 +0100] Report: jobs=2
D [27/May/2012:10:30:15 +0100] Report: jobs-active=0
D [27/May/2012:10:30:15 +0100] Report: printers=1
D [27/May/2012:10:30:15 +0100] Report: printers-implicit=0
D [27/May/2012:10:30:15 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:30:15 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:30:15 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:30:15 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:10:30:15 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:10:30:15 +0100] cupsdAuthorize: No authentication data provided.
D [27/May/2012:10:30:15 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:10:30:15 +0100] Renew-Subscription /
D [27/May/2012:10:30:15 +0100] cupsdIsAuthorized: requesting-user-name="al"
D [27/May/2012:10:30:15 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:10:30:15 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:10:30:15 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:10:30:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/May/2012:10:30:15 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [27/May/2012:10:30:15 +0100] cupsdCloseClient: 16
D [27/May/2012:10:30:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [27/May/2012:10:30:47 +0100] Saving subscriptions.conf...
D [27/May/2012:10:30:47 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:10:33:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:10:33:14 +0100] Report: clients=1
D [27/May/2012:10:33:14 +0100] Report: jobs=2
D [27/May/2012:10:33:14 +0100] Report: jobs-active=0
D [27/May/2012:10:33:14 +0100] Report: printers=1
D [27/May/2012:10:33:14 +0100] Report: printers-implicit=0
D [27/May/2012:10:33:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:33:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:33:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:33:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:10:33:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:10:33:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:10:33:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:10:33:14 +0100] Renew-Subscription /
D [27/May/2012:10:33:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:10:33:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:10:33:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:10:33:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:10:33:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:10:33:45 +0100] Saving subscriptions.conf...
D [27/May/2012:10:33:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:10:38:15 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:10:38:15 +0100] cupsdCloseClient: 16
D [27/May/2012:10:38:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:10:38:15 +0100] Report: clients=0
D [27/May/2012:10:38:15 +0100] Report: jobs=2
D [27/May/2012:10:38:15 +0100] Report: jobs-active=0
D [27/May/2012:10:38:15 +0100] Report: printers=1
D [27/May/2012:10:38:15 +0100] Report: printers-implicit=0
D [27/May/2012:10:38:15 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:38:15 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:38:15 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:47:14 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [27/May/2012:10:47:14 +0100] Report: clients=1
D [27/May/2012:10:47:14 +0100] Report: jobs=2
D [27/May/2012:10:47:14 +0100] Report: jobs-active=0
D [27/May/2012:10:47:14 +0100] Report: printers=1
D [27/May/2012:10:47:14 +0100] Report: printers-implicit=0
D [27/May/2012:10:47:14 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:47:14 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:47:14 +0100] Report: stringpool-total-bytes=40024
D [27/May/2012:10:47:14 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [27/May/2012:10:47:14 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/May/2012:10:47:14 +0100] cupsdAuthorize: Authorized as al using PeerCred
D [27/May/2012:10:47:14 +0100] cupsdReadClient: 16 1.1 Renew-Subscription 1
D [27/May/2012:10:47:14 +0100] Renew-Subscription /
D [27/May/2012:10:47:14 +0100] cupsdIsAuthorized: username="al"
D [27/May/2012:10:47:14 +0100] cupsdMarkDirty(-----S)
D [27/May/2012:10:47:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/May/2012:10:47:14 +0100] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [27/May/2012:10:47:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/May/2012:10:47:45 +0100] Saving subscriptions.conf...
D [27/May/2012:10:47:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/May/2012:10:52:15 +0100] Closing client 16 after 300 seconds of inactivity...
D [27/May/2012:10:52:15 +0100] cupsdCloseClient: 16
D [27/May/2012:10:52:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/May/2012:10:52:15 +0100] Report: clients=0
D [27/May/2012:10:52:15 +0100] Report: jobs=2
D [27/May/2012:10:52:15 +0100] Report: jobs-active=0
D [27/May/2012:10:52:15 +0100] Report: printers=1
D [27/May/2012:10:52:15 +0100] Report: printers-implicit=0
D [27/May/2012:10:52:15 +0100] Report: stringpool-string-count=2213
D [27/May/2012:10:52:15 +0100] Report: stringpool-alloc-bytes=9584
D [27/May/2012:10:52:15 +0100] Report: stringpool-total-bytes=40024