W [17/May/2012:08:03:01 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-Gray..' already exists
W [17/May/2012:08:03:01 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-RGB..' already exists
W [17/May/2012:08:03:01 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet-6300-series' already exists
W [17/May/2012:08:03:01 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-Gray..' already exists
W [17/May/2012:08:03:01 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-RGB..' already exists
W [17/May/2012:08:03:01 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet-6300-series' already exists
W [17/May/2012:09:47:02 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-Gray..' already exists
W [17/May/2012:09:47:02 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-RGB..' already exists
W [17/May/2012:09:47:02 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet-6300-series' already exists
W [17/May/2012:16:09:06 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-Gray..' already exists
W [17/May/2012:16:09:06 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-RGB..' already exists
W [17/May/2012:16:09:06 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet-6300-series' already exists
E [17/May/2012:16:23:40 -0500] Returning IPP client-error-not-possible for CUPS-Add-Modify-Printer (ipp://localhost/printers/class2) from localhost
E [17/May/2012:16:23:40 -0500] Returning IPP client-error-not-possible for CUPS-Add-Modify-Printer (ipp://localhost/printers/class2) from localhost
W [17/May/2012:17:26:34 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-Gray..' already exists
W [17/May/2012:17:26:34 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-RGB..' already exists
W [17/May/2012:17:26:34 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet-6300-series' already exists
W [17/May/2012:17:34:20 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-Gray..' already exists
W [17/May/2012:17:34:20 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-RGB..' already exists
W [17/May/2012:17:34:20 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet-6300-series' already exists
I [17/May/2012:17:39:19 -0500] Remote access is disabled.
D [17/May/2012:17:39:19 -0500] Added auto ServerAlias mlcx500
I [17/May/2012:17:39:19 -0500] Loaded configuration file "/etc/cups/cupsd.conf"
I [17/May/2012:17:39:19 -0500] Using default TempDir of /var/spool/cups/tmp...
I [17/May/2012:17:39:19 -0500] Configured for up to 100 clients.
I [17/May/2012:17:39:19 -0500] Allowing up to 100 client connections per host.
I [17/May/2012:17:39:19 -0500] Using policy "default" as the default.
I [17/May/2012:17:39:19 -0500] Full reload is required.
I [17/May/2012:17:39:19 -0500] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 39 types, 56 filters...
D [17/May/2012:17:39:19 -0500] Loading printer Officejet-6300-series...
D [17/May/2012:17:39:19 -0500] load_ppd: Loading /var/cache/cups/Officejet-6300-series.data...
D [17/May/2012:17:39:19 -0500] Calling DeleteDevice(cups-Officejet-6300-series)
D [17/May/2012:17:39:19 -0500] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [17/May/2012:17:39:19 -0500] Using profile id of Officejet-6300-series-Gray..
D [17/May/2012:17:39:19 -0500] Calling CreateProfile(Officejet-6300-series-Gray..,temp)
D [17/May/2012:17:39:19 -0500] created profile /org/freedesktop/ColorManager/profiles/Officejet_6300_series_Gray__
D [17/May/2012:17:39:19 -0500] Using profile id of Officejet-6300-series-RGB..
D [17/May/2012:17:39:19 -0500] Calling CreateProfile(Officejet-6300-series-RGB..,temp)
D [17/May/2012:17:39:19 -0500] created profile /org/freedesktop/ColorManager/profiles/Officejet_6300_series_RGB__
I [17/May/2012:17:39:19 -0500] Registering ICC color profiles for "Officejet-6300-series"
D [17/May/2012:17:39:19 -0500] Calling CreateDevice(cups-Officejet-6300-series,temp)
D [17/May/2012:17:39:19 -0500] created device /org/freedesktop/ColorManager/devices/cups_Officejet_6300_series
D [17/May/2012:17:39:19 -0500] Calling /org/freedesktop/ColorManager/devices/cups_Officejet_6300_series:AddProfile(/org/freedesktop/ColorManager/profiles/Officejet_6300_series_Gray__) [soft]
D [17/May/2012:17:39:19 -0500] Calling /org/freedesktop/ColorManager/devices/cups_Officejet_6300_series:AddProfile(/org/freedesktop/ColorManager/profiles/Officejet_6300_series_RGB__) [soft]
D [17/May/2012:17:39:19 -0500] cupsdRegisterPrinter(p=0x7ffe0a21f390(Officejet-6300-series))
D [17/May/2012:17:39:19 -0500] load_ppd: Loading /var/cache/cups/Officejet-6300-series.data...
D [17/May/2012:17:39:19 -0500] Calling DeleteDevice(cups-Officejet-6300-series)
D [17/May/2012:17:39:19 -0500] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [17/May/2012:17:39:19 -0500] Using profile id of Officejet-6300-series-Gray..
D [17/May/2012:17:39:19 -0500] Calling CreateProfile(Officejet-6300-series-Gray..,temp)
W [17/May/2012:17:39:19 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-Gray..' already exists
D [17/May/2012:17:39:19 -0500] Using profile id of Officejet-6300-series-RGB..
D [17/May/2012:17:39:19 -0500] Calling CreateProfile(Officejet-6300-series-RGB..,temp)
W [17/May/2012:17:39:19 -0500] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet-6300-series-RGB..' already exists
I [17/May/2012:17:39:19 -0500] Registering ICC color profiles for "Officejet-6300-series"
D [17/May/2012:17:39:19 -0500] Calling CreateDevice(cups-Officejet-6300-series,temp)
W [17/May/2012:17:39:19 -0500] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Officejet-6300-series' already exists
D [17/May/2012:17:39:19 -0500] cupsdRegisterPrinter(p=0x7ffe0a21f390(Officejet-6300-series))
D [17/May/2012:17:39:19 -0500] Loading printer z6...
D [17/May/2012:17:39:19 -0500] cupsdMarkDirty(P-----)
D [17/May/2012:17:39:19 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
D [17/May/2012:17:39:19 -0500] load_ppd: Loading /etc/cups/ppd/z6.ppd...
D [17/May/2012:17:39:19 -0500] Calling DeleteDevice(cups-z6)
D [17/May/2012:17:39:19 -0500] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [17/May/2012:17:39:19 -0500] cannot open /etc/cups/ppd/z6.ppd
D [17/May/2012:17:39:19 -0500] cupsdRegisterPrinter(p=0x7ffe0a289990(z6))
D [17/May/2012:17:39:19 -0500] cupsdLoadRemoteCache: Not loading remote cache.
I [17/May/2012:17:39:19 -0500] Loading job cache file "/var/cache/cups/job.cache"...
D [17/May/2012:17:39:19 -0500] [Job 1] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 2] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 3] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 4] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 5] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 10] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 11] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 12] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 13] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 14] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 15] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 16] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 17] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 52] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 53] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 54] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 55] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 56] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 57] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 58] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 59] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 60] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 61] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 62] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 63] Loading from cache...
D [17/May/2012:17:39:19 -0500] [Job 64] Loading from cache...
D [17/May/2012:17:39:19 -0500] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [17/May/2012:17:39:19 -0500] Full reload complete.
D [17/May/2012:17:39:19 -0500] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [17/May/2012:17:39:19 -0500] Cleaning out old files in "/var/spool/cups/tmp"...
D [17/May/2012:17:39:19 -0500] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [17/May/2012:17:39:19 -0500] Cleaning out old files in "/var/cache/cups"...
I [17/May/2012:17:39:19 -0500] Listening to [v1.::1]:631 on fd 9...
I [17/May/2012:17:39:19 -0500] Listening to 127.0.0.1:631 on fd 10...
I [17/May/2012:17:39:19 -0500] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [17/May/2012:17:39:19 -0500] Resuming new connection processing...
D [17/May/2012:17:39:19 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/May/2012:17:39:19 -0500] Discarding unused server-started event...
D [17/May/2012:17:39:20 -0500] Report: clients=0
D [17/May/2012:17:39:20 -0500] Report: jobs=26
D [17/May/2012:17:39:20 -0500] Report: jobs-active=0
D [17/May/2012:17:39:20 -0500] Report: printers=2
D [17/May/2012:17:39:20 -0500] Report: printers-implicit=0
D [17/May/2012:17:39:20 -0500] Report: stringpool-string-count=22747
D [17/May/2012:17:39:20 -0500] Report: stringpool-alloc-bytes=11368
D [17/May/2012:17:39:20 -0500] Report: stringpool-total-bytes=421360
D [17/May/2012:17:39:31 -0500] cupsdAcceptClient: 14 from localhost:631 (IPv6)
D [17/May/2012:17:39:31 -0500] Avahi client started
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:39:31 -0500] cupsdCloseClient: 14
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAcceptClient: 14 from localhost (Domain)
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [17/May/2012:17:39:31 -0500] Get-Printer-Attributes ipp://localhost:631/printers/z6
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/z6) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 POST /printers/z6 HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 1.1 Create-Job 1
D [17/May/2012:17:39:31 -0500] Create-Job ipp://localhost:631/printers/z6
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(----J-)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] add_job: requesting-user-name="mclaborn"
I [17/May/2012:17:39:31 -0500] [Job 65] Adding start banner page "none".
D [17/May/2012:17:39:31 -0500] Discarding unused job-created event...
I [17/May/2012:17:39:31 -0500] [Job 65] Queued on "z6" by "mclaborn".
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/z6) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 POST /printers/z6 HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 1.1 Send-Document 1
D [17/May/2012:17:39:31 -0500] Send-Document ipp://localhost:631/printers/z6
D [17/May/2012:17:39:31 -0500] cupsdIsAuthorized: requesting-user-name="mclaborn"
D [17/May/2012:17:39:31 -0500] [Job 65] Auto-typing file...
D [17/May/2012:17:39:31 -0500] [Job 65] Request file type is application/postscript.
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(----J-)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [17/May/2012:17:39:31 -0500] [Job 65] File of type application/postscript queued by "mclaborn".
I [17/May/2012:17:39:31 -0500] [Job 65] Adding end banner page "none".
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(----J-)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(----J-)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(-----S)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] [Job 65] Sending job to queue tagged as raw...
D [17/May/2012:17:39:31 -0500] [Job 65] job-sheets=none,none
D [17/May/2012:17:39:31 -0500] [Job 65] argv[0]="z6"
D [17/May/2012:17:39:31 -0500] [Job 65] argv[1]="65"
D [17/May/2012:17:39:31 -0500] [Job 65] argv[2]="mclaborn"
D [17/May/2012:17:39:31 -0500] [Job 65] argv[3]=" Java Printing"
D [17/May/2012:17:39:31 -0500] [Job 65] argv[4]="1"
D [17/May/2012:17:39:31 -0500] [Job 65] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:f3ea6d92-5e73-3ee9-6fff-59df1e8d7e02 job-originating-host-name=localhost time-at-creation=1337294371 time-at-processing=1337294371"
D [17/May/2012:17:39:31 -0500] [Job 65] argv[6]="/var/spool/cups/d00065-001"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[8]="HOME=/var/spool/cups/tmp"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[10]="SERVER_ADMIN=root@mlcx500"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[11]="SOFTWARE=CUPS/1.5.2"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[13]="USER=root"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[16]="IPP_PORT=631"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[17]="CHARSET=utf-8"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[18]="LANG=en_US.UTF-8"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[19]="PPD=/etc/cups/ppd/z6.ppd"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[20]="RIP_MAX_CACHE=128m"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[21]="CONTENT_TYPE=application/postscript"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[22]="DEVICE_URI=usb://Zebra%20Technologies/ZTC%20LP2844-Z-200dpi?serial=46J083000010"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[23]="PRINTER_INFO=Zebra Technologies ZTC LP2844-Z-200dpi"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[24]="PRINTER_LOCATION=mlcx500"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[25]="PRINTER=z6"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[26]="PRINTER_STATE_REASONS=none"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[27]="CUPS_FILETYPE=document"
D [17/May/2012:17:39:31 -0500] [Job 65] envp[28]="AUTH_I****"
I [17/May/2012:17:39:31 -0500] [Job 65] Started backend /usr/lib/cups/backend/usb (PID 23326)
D [17/May/2012:17:39:31 -0500] Discarding unused job-state-changed event...
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/z6) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:39:31 -0500] cupsdCloseClient: 14
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] [Job 65] print_device
D [17/May/2012:17:39:31 -0500] [Job 65] libusb_get_device_list=12
D [17/May/2012:17:39:31 -0500] [Job 65] STATE: +connecting-to-device
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(-----S)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] [Job 65] STATE: -connecting-to-device
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(-----S)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
I [17/May/2012:17:39:31 -0500] [Job 65] Sending data to printer.
D [17/May/2012:17:39:31 -0500] [Job 65] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [17/May/2012:17:39:31 -0500] [Job 65] PAGE: 1 1
D [17/May/2012:17:39:31 -0500] Discarding unused job-progress event...
D [17/May/2012:17:39:31 -0500] [Job 65] Read 8192 bytes of print data...
D [17/May/2012:17:39:31 -0500] Discarding unused job-progress event...
D [17/May/2012:17:39:31 -0500] cupsdMarkDirty(-----S)
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] [Job 65] Wrote 8192 bytes of print data...
D [17/May/2012:17:39:31 -0500] [Job 65] Sending print file, 8192 bytes...
D [17/May/2012:17:39:31 -0500] [Job 65] Read 8192 bytes of print data...
D [17/May/2012:17:39:31 -0500] [Job 65] Wrote 8192 bytes of print data...
D [17/May/2012:17:39:31 -0500] [Job 65] Sending print file, 16384 bytes...
D [17/May/2012:17:39:31 -0500] [Job 65] Read 8192 bytes of print data...
D [17/May/2012:17:39:31 -0500] [Job 65] Wrote 8192 bytes of print data...
D [17/May/2012:17:39:31 -0500] [Job 65] Sending print file, 24576 bytes...
D [17/May/2012:17:39:31 -0500] [Job 65] Read 3043 bytes of print data...
D [17/May/2012:17:39:31 -0500] [Job 65] Wrote 3043 bytes of print data...
D [17/May/2012:17:39:31 -0500] [Job 65] Sending print file, 27619 bytes...
D [17/May/2012:17:39:31 -0500] [Job 65] Sent 27619 bytes...
D [17/May/2012:17:39:31 -0500] [Job 65] Waiting for read thread to exit...
D [17/May/2012:17:39:31 -0500] cupsdAcceptClient: 14 from localhost (Domain)
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 1.1 Get-Notifications 1
D [17/May/2012:17:39:31 -0500] Get-Notifications /
D [17/May/2012:17:39:31 -0500] cupsdIsAuthorized: requesting-user-name="mclaborn"
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAcceptClient: 17 from localhost (Domain)
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 17 POST / HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
D [17/May/2012:17:39:31 -0500] Get-Printer-Attributes ipp://localhost/printers/z6
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/z6) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:39:31 -0500] cupsdCloseClient: 14
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 17 GET /printers/z6.ppd HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 17 Closing because Keep-Alive disabled
D [17/May/2012:17:39:31 -0500] cupsdCloseClient: 17
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAcceptClient: 14 from localhost (Domain)
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: Authorized as mclaborn using PeerCred
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:39:31 -0500] CUPS-Get-Printers
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: Authorized as mclaborn using PeerCred
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Classes 1
D [17/May/2012:17:39:31 -0500] CUPS-Get-Classes
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:31 -0500] cupsdAuthorize: Authorized as mclaborn using PeerCred
D [17/May/2012:17:39:31 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [17/May/2012:17:39:31 -0500] CUPS-Get-Default
D [17/May/2012:17:39:31 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/May/2012:17:39:31 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/May/2012:17:39:38 -0500] [Job 65] Read thread still active, aborting the pending read...
D [17/May/2012:17:39:39 -0500] PID 23326 (/usr/lib/cups/backend/usb) exited with no errors.
D [17/May/2012:17:39:39 -0500] Discarding unused job-completed event...
I [17/May/2012:17:39:39 -0500] [Job 65] Job completed.
D [17/May/2012:17:39:39 -0500] cupsdMarkDirty(----J-)
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:39 -0500] cupsdMarkDirty(-----S)
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:39 -0500] cupsdAcceptClient: 16 from localhost (Domain)
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 16 POST / HTTP/1.1
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [17/May/2012:17:39:39 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 16 1.1 Get-Notifications 1
D [17/May/2012:17:39:39 -0500] Get-Notifications /
D [17/May/2012:17:39:39 -0500] cupsdIsAuthorized: requesting-user-name="mclaborn"
D [17/May/2012:17:39:39 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:39:39 -0500] cupsdCloseClient: 16
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/May/2012:17:39:39 -0500] cupsdAuthorize: Authorized as mclaborn using PeerCred
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:39:39 -0500] CUPS-Get-Printers
D [17/May/2012:17:39:39 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/May/2012:17:39:39 -0500] cupsdAuthorize: Authorized as mclaborn using PeerCred
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Classes 1
D [17/May/2012:17:39:39 -0500] CUPS-Get-Classes
D [17/May/2012:17:39:39 -0500] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [17/May/2012:17:39:39 -0500] cupsdAuthorize: Authorized as mclaborn using PeerCred
D [17/May/2012:17:39:39 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [17/May/2012:17:39:39 -0500] CUPS-Get-Default
D [17/May/2012:17:39:39 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/May/2012:17:39:39 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/May/2012:17:39:40 -0500] [Job 65] Unloading...
I [17/May/2012:17:39:50 -0500] Saving printers.conf...
I [17/May/2012:17:39:50 -0500] Saving job.cache...
I [17/May/2012:17:39:51 -0500] Saving subscriptions.conf...
D [17/May/2012:17:39:51 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [17/May/2012:17:41:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv6)
D [17/May/2012:17:41:03 -0500] Report: clients=2
D [17/May/2012:17:41:03 -0500] Report: jobs=27
D [17/May/2012:17:41:03 -0500] Report: jobs-active=0
D [17/May/2012:17:41:03 -0500] Report: printers=2
D [17/May/2012:17:41:03 -0500] Report: printers-implicit=0
D [17/May/2012:17:41:03 -0500] Report: stringpool-string-count=22883
D [17/May/2012:17:41:03 -0500] Report: stringpool-alloc-bytes=11920
D [17/May/2012:17:41:03 -0500] Report: stringpool-total-bytes=424216
D [17/May/2012:17:41:03 -0500] cupsdAcceptClient: 17 from localhost:631 (IPv6)
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:41:03 -0500] cupsdCloseClient: 16
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:41:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv4)
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 17 WAITING Closing on EOF
D [17/May/2012:17:41:03 -0500] cupsdCloseClient: 17
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 16 POST / HTTP/1.1
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:41:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [17/May/2012:17:41:03 -0500] CUPS-Get-Default
D [17/May/2012:17:41:03 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:41:03 -0500] cupsdAcceptClient: 17 from localhost:631 (IPv6)
D [17/May/2012:17:41:03 -0500] cupsdAcceptClient: 18 from localhost:631 (IPv6)
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 17 WAITING Closing on EOF
D [17/May/2012:17:41:03 -0500] cupsdCloseClient: 17
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 18 WAITING Closing on EOF
D [17/May/2012:17:41:03 -0500] cupsdCloseClient: 18
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 16 POST / HTTP/1.1
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:41:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:41:03 -0500] CUPS-Get-Printers
D [17/May/2012:17:41:03 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:41:03 -0500] cupsdAcceptClient: 17 from localhost:631 (IPv6)
D [17/May/2012:17:41:03 -0500] cupsdReadClient: 17 WAITING Closing on EOF
D [17/May/2012:17:41:03 -0500] cupsdCloseClient: 17
D [17/May/2012:17:41:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:41:33 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:41:33 -0500] cupsdCloseClient: 16
D [17/May/2012:17:41:33 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:43:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv6)
D [17/May/2012:17:43:03 -0500] Report: clients=2
D [17/May/2012:17:43:03 -0500] Report: jobs=27
D [17/May/2012:17:43:03 -0500] Report: jobs-active=0
D [17/May/2012:17:43:03 -0500] Report: printers=2
D [17/May/2012:17:43:03 -0500] Report: printers-implicit=0
D [17/May/2012:17:43:03 -0500] Report: stringpool-string-count=22883
D [17/May/2012:17:43:03 -0500] Report: stringpool-alloc-bytes=11920
D [17/May/2012:17:43:03 -0500] Report: stringpool-total-bytes=424216
D [17/May/2012:17:43:03 -0500] cupsdAcceptClient: 17 from localhost:631 (IPv6)
D [17/May/2012:17:43:03 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:43:03 -0500] cupsdCloseClient: 16
D [17/May/2012:17:43:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:43:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv4)
D [17/May/2012:17:43:03 -0500] cupsdReadClient: 17 WAITING Closing on EOF
D [17/May/2012:17:43:03 -0500] cupsdCloseClient: 17
D [17/May/2012:17:43:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:43:03 -0500] cupsdReadClient: 16 POST / HTTP/1.1
D [17/May/2012:17:43:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:43:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:43:03 -0500] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [17/May/2012:17:43:03 -0500] CUPS-Get-Default
D [17/May/2012:17:43:03 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/May/2012:17:43:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:43:03 -0500] cupsdAcceptClient: 17 from localhost:631 (IPv6)
D [17/May/2012:17:43:03 -0500] cupsdReadClient: 17 WAITING Closing on EOF
D [17/May/2012:17:43:03 -0500] cupsdCloseClient: 17
D [17/May/2012:17:43:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:43:03 -0500] cupsdReadClient: 16 POST / HTTP/1.1
D [17/May/2012:17:43:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:43:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:43:03 -0500] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:43:03 -0500] CUPS-Get-Printers
D [17/May/2012:17:43:03 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:43:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:43:33 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:43:33 -0500] cupsdCloseClient: 16
D [17/May/2012:17:43:33 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:44:40 -0500] Closing client 14 after 300 seconds of inactivity...
D [17/May/2012:17:44:40 -0500] cupsdCloseClient: 14
D [17/May/2012:17:44:40 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:44:40 -0500] Report: clients=0
D [17/May/2012:17:44:40 -0500] Report: jobs=27
D [17/May/2012:17:44:40 -0500] Report: jobs-active=0
D [17/May/2012:17:44:40 -0500] Report: printers=2
D [17/May/2012:17:44:40 -0500] Report: printers-implicit=0
D [17/May/2012:17:44:40 -0500] Report: stringpool-string-count=22883
D [17/May/2012:17:44:40 -0500] Report: stringpool-alloc-bytes=11920
D [17/May/2012:17:44:40 -0500] Report: stringpool-total-bytes=424216
D [17/May/2012:17:45:03 -0500] cupsdAcceptClient: 14 from localhost:631 (IPv6)
D [17/May/2012:17:45:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv6)
D [17/May/2012:17:45:03 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:45:03 -0500] cupsdCloseClient: 14
D [17/May/2012:17:45:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:45:03 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:45:03 -0500] cupsdCloseClient: 16
D [17/May/2012:17:45:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:45:03 -0500] cupsdAcceptClient: 14 from localhost:631 (IPv4)
D [17/May/2012:17:45:03 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:45:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:45:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:45:03 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [17/May/2012:17:45:03 -0500] CUPS-Get-Default
D [17/May/2012:17:45:03 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/May/2012:17:45:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:45:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv6)
D [17/May/2012:17:45:03 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:45:03 -0500] cupsdCloseClient: 16
D [17/May/2012:17:45:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:45:03 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:45:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:45:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:45:03 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:45:03 -0500] CUPS-Get-Printers
D [17/May/2012:17:45:03 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:45:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:45:33 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:45:33 -0500] cupsdCloseClient: 14
D [17/May/2012:17:45:33 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:46:15 -0500] cupsdAcceptClient: 14 from localhost (Domain)
D [17/May/2012:17:46:15 -0500] Report: clients=1
D [17/May/2012:17:46:15 -0500] Report: jobs=27
D [17/May/2012:17:46:15 -0500] Report: jobs-active=0
D [17/May/2012:17:46:15 -0500] Report: printers=2
D [17/May/2012:17:46:15 -0500] Report: printers-implicit=0
D [17/May/2012:17:46:15 -0500] Report: stringpool-string-count=22883
D [17/May/2012:17:46:15 -0500] Report: stringpool-alloc-bytes=11920
D [17/May/2012:17:46:15 -0500] Report: stringpool-total-bytes=424216
D [17/May/2012:17:46:15 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:46:15 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:46:15 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:46:15 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:46:15 -0500] CUPS-Get-Printers
D [17/May/2012:17:46:15 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:46:15 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:46:15 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:46:15 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:46:15 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:46:15 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [17/May/2012:17:46:15 -0500] CUPS-Get-Default
D [17/May/2012:17:46:15 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/May/2012:17:46:15 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:46:15 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:46:15 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:46:15 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:46:15 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:46:15 -0500] CUPS-Get-Printers
D [17/May/2012:17:46:15 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:46:15 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:46:15 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:46:15 -0500] cupsdCloseClient: 14
D [17/May/2012:17:46:15 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:47:03 -0500] cupsdAcceptClient: 14 from localhost:631 (IPv6)
D [17/May/2012:17:47:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv6)
D [17/May/2012:17:47:03 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:47:03 -0500] cupsdCloseClient: 14
D [17/May/2012:17:47:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:47:03 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:47:03 -0500] cupsdCloseClient: 16
D [17/May/2012:17:47:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:47:03 -0500] cupsdAcceptClient: 14 from localhost:631 (IPv4)
D [17/May/2012:17:47:03 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:47:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:47:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:47:03 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [17/May/2012:17:47:03 -0500] CUPS-Get-Default
D [17/May/2012:17:47:03 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [17/May/2012:17:47:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:47:03 -0500] cupsdAcceptClient: 16 from localhost:631 (IPv6)
D [17/May/2012:17:47:03 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [17/May/2012:17:47:03 -0500] cupsdCloseClient: 16
D [17/May/2012:17:47:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:47:03 -0500] cupsdReadClient: 14 POST / HTTP/1.1
D [17/May/2012:17:47:03 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/May/2012:17:47:03 -0500] cupsdAuthorize: No authentication data provided.
D [17/May/2012:17:47:03 -0500] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [17/May/2012:17:47:03 -0500] CUPS-Get-Printers
D [17/May/2012:17:47:03 -0500] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [17/May/2012:17:47:03 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [17/May/2012:17:47:33 -0500] cupsdReadClient: 14 WAITING Closing on EOF
D [17/May/2012:17:47:33 -0500] cupsdCloseClient: 14
D [17/May/2012:17:47:33 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/May/2012:17:47:33 -0500] Report: clients=0
D [17/May/2012:17:47:33 -0500] Report: jobs=27
D [17/May/2012:17:47:33 -0500] Report: jobs-active=0
D [17/May/2012:17:47:33 -0500] Report: printers=2
D [17/May/2012:17:47:33 -0500] Report: printers-implicit=0
D [17/May/2012:17:47:33 -0500] Report: stringpool-string-count=22883
D [17/May/2012:17:47:33 -0500] Report: stringpool-alloc-bytes=11920
D [17/May/2012:17:47:33 -0500] Report: stringpool-total-bytes=424216