W [12/Oct/2013:16:51:42 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-Gray..' already exists W [12/Oct/2013:16:51:42 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-RGB..' already exists W [12/Oct/2013:16:51:42 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Samsung-ML-2950-Gray..' already exists W [12/Oct/2013:16:51:44 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-Gray..' already exists W [12/Oct/2013:16:51:44 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-RGB..' already exists W [12/Oct/2013:16:51:44 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Samsung-ML-2950-Gray..' already exists W [12/Oct/2013:16:52:39 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-Gray..' already exists W [12/Oct/2013:16:52:39 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-RGB..' already exists W [12/Oct/2013:16:52:39 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Samsung-ML-2950-Gray..' already exists I [12/Oct/2013:16:56:19 -0400] Listening to [v1.::1]:631 (IPv6) I [12/Oct/2013:16:56:19 -0400] Listening to 127.0.0.1:631 (IPv4) I [12/Oct/2013:16:56:19 -0400] Listening to /var/run/cups/cups.sock (Domain) I [12/Oct/2013:16:56:19 -0400] Remote access is disabled. D [12/Oct/2013:16:56:19 -0400] Added auto ServerAlias don-NY553AAR-ABA-e9220y I [12/Oct/2013:16:56:19 -0400] Loaded configuration file "/etc/cups/cupsd.conf" I [12/Oct/2013:16:56:19 -0400] Using default TempDir of /var/spool/cups/tmp... I [12/Oct/2013:16:56:19 -0400] Configured for up to 100 clients. I [12/Oct/2013:16:56:19 -0400] Allowing up to 100 client connections per host. I [12/Oct/2013:16:56:19 -0400] Using policy "default" as the default. D [12/Oct/2013:16:56:19 -0400] load_ppd: Loading /var/cache/cups/EPSON-WP-4530-Series.data... D [12/Oct/2013:16:56:19 -0400] cupsdMarkDirty(P----) D [12/Oct/2013:16:56:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy" D [12/Oct/2013:16:56:19 -0400] load_ppd: Loading /etc/cups/ppd/EPSON-WP-4530-Series.ppd... D [12/Oct/2013:16:56:19 -0400] load_ppd: Saving /var/cache/cups/EPSON-WP-4530-Series.data... D [12/Oct/2013:16:56:19 -0400] cupsdRegisterPrinter(p=0x7f59247013a0(EPSON-WP-4530-Series)) D [12/Oct/2013:16:56:19 -0400] cupsdMarkDirty(P----) D [12/Oct/2013:16:56:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:56:19 -0400] load_ppd: Loading /etc/cups/ppd/PDF.ppd... D [12/Oct/2013:16:56:19 -0400] cupsdRegisterPrinter(p=0x7f59246fa420(PDF)) D [12/Oct/2013:16:56:19 -0400] load_ppd: Loading /var/cache/cups/Samsung-ML-2950.data... D [12/Oct/2013:16:56:19 -0400] cupsdRegisterPrinter(p=0x7f592472e440(Samsung-ML-2950)) D [12/Oct/2013:16:56:19 -0400] cupsdMarkDirty(--p--) D [12/Oct/2013:16:56:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [12/Oct/2013:16:56:19 -0400] Partial reload complete. D [12/Oct/2013:16:56:19 -0400] Calling FindDeviceById(cups-EPSON-WP-4530-Series) D [12/Oct/2013:16:56:19 -0400] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_EPSON_WP_4530_Series) D [12/Oct/2013:16:56:19 -0400] Using profile ID "EPSON-WP-4530-Series-Gray..". D [12/Oct/2013:16:56:19 -0400] Calling CreateProfile(EPSON-WP-4530-Series-Gray..,temp) W [12/Oct/2013:16:56:19 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-Gray..' already exists D [12/Oct/2013:16:56:19 -0400] Using profile ID "EPSON-WP-4530-Series-RGB..". D [12/Oct/2013:16:56:19 -0400] Calling CreateProfile(EPSON-WP-4530-Series-RGB..,temp) W [12/Oct/2013:16:56:19 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-RGB..' already exists I [12/Oct/2013:16:56:19 -0400] Registering ICC color profiles for "EPSON-WP-4530-Series". D [12/Oct/2013:16:56:19 -0400] Calling CreateDevice(cups-EPSON-WP-4530-Series,temp) D [12/Oct/2013:16:56:19 -0400] Created device "/org/freedesktop/ColorManager/devices/cups_EPSON_WP_4530_Series". D [12/Oct/2013:16:56:19 -0400] Calling FindDeviceById(cups-PDF) D [12/Oct/2013:16:56:19 -0400] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id 'cups-PDF' does not exist D [12/Oct/2013:16:56:19 -0400] Calling FindDeviceById(cups-Samsung-ML-2950) D [12/Oct/2013:16:56:19 -0400] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_Samsung_ML_2950) D [12/Oct/2013:16:56:19 -0400] Using profile ID "Samsung-ML-2950-Gray..". D [12/Oct/2013:16:56:19 -0400] Calling CreateProfile(Samsung-ML-2950-Gray..,temp) W [12/Oct/2013:16:56:19 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Samsung-ML-2950-Gray..' already exists I [12/Oct/2013:16:56:19 -0400] Registering ICC color profiles for "Samsung-ML-2950". D [12/Oct/2013:16:56:19 -0400] Calling CreateDevice(cups-Samsung-ML-2950,temp) D [12/Oct/2013:16:56:19 -0400] Created device "/org/freedesktop/ColorManager/devices/cups_Samsung_ML_2950". I [12/Oct/2013:16:56:19 -0400] Listening to [v1.::1]:631 on fd 8... I [12/Oct/2013:16:56:19 -0400] Listening to 127.0.0.1:631 on fd 10... I [12/Oct/2013:16:56:19 -0400] Listening to /var/run/cups/cups.sock:631 on fd 11... I [12/Oct/2013:16:56:19 -0400] Resuming new connection processing... D [12/Oct/2013:16:56:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:56:19 -0400] Notifier dbus started - PID = 3782 D [12/Oct/2013:16:56:19 -0400] Notifier dbus started - PID = 3783 D [12/Oct/2013:16:56:19 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:56:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:56:19 -0400] [Notifier] state=3 D [12/Oct/2013:16:56:19 -0400] Report: clients=0 D [12/Oct/2013:16:56:19 -0400] Report: jobs=68 D [12/Oct/2013:16:56:19 -0400] Report: jobs-active=0 D [12/Oct/2013:16:56:19 -0400] Report: printers=3 D [12/Oct/2013:16:56:19 -0400] Report: stringpool-string-count=155134 D [12/Oct/2013:16:56:19 -0400] Report: stringpool-alloc-bytes=12904 D [12/Oct/2013:16:56:19 -0400] Report: stringpool-total-bytes=1317664 D [12/Oct/2013:16:56:19 -0400] PID 3315 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15. D [12/Oct/2013:16:56:19 -0400] PID 3316 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15. D [12/Oct/2013:16:56:19 -0400] [Notifier] state=3 D [12/Oct/2013:16:56:19 -0400] [Notifier] Connected to D-BUS D [12/Oct/2013:16:56:19 -0400] [Notifier] Connected to D-BUS D [12/Oct/2013:16:56:19 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:56:19 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:56:20 -0400] [Client 19] Accepted from localhost (Domain) D [12/Oct/2013:16:56:20 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:56:31 -0400] [Client 20] Accepted from localhost (Domain) D [12/Oct/2013:16:56:31 -0400] [Client 20] Waiting for request. D [12/Oct/2013:16:56:31 -0400] [Client 20] POST / HTTP/1.1 D [12/Oct/2013:16:56:31 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:56:31 -0400] [Client 20] No authentication data provided. D [12/Oct/2013:16:56:31 -0400] [Client 20] 2.0 Get-Notifications 11 D [12/Oct/2013:16:56:31 -0400] Get-Notifications / D [12/Oct/2013:16:56:31 -0400] cupsdIsAuthorized: requesting-user-name="don" D [12/Oct/2013:16:56:31 -0400] [Client 20] Returning IPP successful-ok for Get-Notifications (/) from localhost D [12/Oct/2013:16:56:31 -0400] [Client 20] Content-Length: 127 D [12/Oct/2013:16:56:31 -0400] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f59246f4370(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:56:31 -0400] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:56:31 -0400] [Client 20] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:56:31 -0400] [Client 20] Waiting for request. D [12/Oct/2013:16:56:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:56:31 -0400] [Client 20] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:56:31 -0400] [Client 20] Closing connection. D [12/Oct/2013:16:56:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:56:49 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:56:49 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:56:49 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [12/Oct/2013:16:56:49 -0400] Saving printers.conf... I [12/Oct/2013:16:56:49 -0400] Generating printcap /var/run/cups/printcap... I [12/Oct/2013:16:56:49 -0400] Saving subscriptions.conf... D [12/Oct/2013:16:56:49 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [12/Oct/2013:16:57:31 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:57:31 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:57:31 -0400] Report: clients=2 D [12/Oct/2013:16:57:31 -0400] Report: jobs=68 D [12/Oct/2013:16:57:31 -0400] Report: jobs-active=0 D [12/Oct/2013:16:57:31 -0400] Report: printers=3 D [12/Oct/2013:16:57:31 -0400] Report: stringpool-string-count=155134 D [12/Oct/2013:16:57:31 -0400] Report: stringpool-alloc-bytes=12904 D [12/Oct/2013:16:57:31 -0400] Report: stringpool-total-bytes=1317664 D [12/Oct/2013:16:57:31 -0400] [Client 16] POST / HTTP/1.1 D [12/Oct/2013:16:57:31 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:57:31 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:57:31 -0400] [Client 16] 2.0 Get-Notifications 12 D [12/Oct/2013:16:57:31 -0400] Get-Notifications / D [12/Oct/2013:16:57:31 -0400] cupsdIsAuthorized: requesting-user-name="don" D [12/Oct/2013:16:57:31 -0400] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost D [12/Oct/2013:16:57:31 -0400] [Client 16] Content-Length: 127 D [12/Oct/2013:16:57:31 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f59246f4370(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:57:31 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:57:31 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:57:31 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:57:31 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:57:31 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:57:31 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:57:31 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [12/Oct/2013:16:58:04 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:04 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:04 -0400] [Client 16] PUT /admin/conf/cupsd.conf HTTP/1.1 D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:04 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:04 -0400] cupsdIsAuthorized: username="" D [12/Oct/2013:16:58:04 -0400] [Client 16] WWW-Authenticate: Basic realm="CUPS", trc="y" D [12/Oct/2013:16:58:04 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:04 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:04 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:04 -0400] [Client 20] Accepted from localhost (Domain) D [12/Oct/2013:16:58:04 -0400] [Client 20] Waiting for request. D [12/Oct/2013:16:58:04 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:58:04 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [12/Oct/2013:16:58:04 -0400] [Client 20] PUT /admin/conf/cupsd.conf HTTP/1.1 D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:04 -0400] [Client 20] Authorized as root using PeerCred D [12/Oct/2013:16:58:04 -0400] cupsdIsAuthorized: username="root" I [12/Oct/2013:16:58:04 -0400] Installing config file "/etc/cups/cupsd.conf"... D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:04 -0400] [Client 19] Closing connection. D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [12/Oct/2013:16:58:04 -0400] [Client 20] Closing connection. D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" I [12/Oct/2013:16:58:04 -0400] Listening to [v1.::1]:631 (IPv6) I [12/Oct/2013:16:58:04 -0400] Listening to 127.0.0.1:631 (IPv4) I [12/Oct/2013:16:58:04 -0400] Listening to /var/run/cups/cups.sock (Domain) I [12/Oct/2013:16:58:04 -0400] Remote access is disabled. D [12/Oct/2013:16:58:04 -0400] Added auto ServerAlias don-NY553AAR-ABA-e9220y I [12/Oct/2013:16:58:04 -0400] Loaded configuration file "/etc/cups/cupsd.conf" I [12/Oct/2013:16:58:04 -0400] Using default TempDir of /var/spool/cups/tmp... I [12/Oct/2013:16:58:04 -0400] Configured for up to 100 clients. I [12/Oct/2013:16:58:04 -0400] Allowing up to 100 client connections per host. I [12/Oct/2013:16:58:04 -0400] Using policy "default" as the default. D [12/Oct/2013:16:58:04 -0400] load_ppd: Loading /var/cache/cups/EPSON-WP-4530-Series.data... D [12/Oct/2013:16:58:04 -0400] cupsdMarkDirty(P----) D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy" D [12/Oct/2013:16:58:04 -0400] load_ppd: Loading /etc/cups/ppd/EPSON-WP-4530-Series.ppd... D [12/Oct/2013:16:58:04 -0400] load_ppd: Saving /var/cache/cups/EPSON-WP-4530-Series.data... D [12/Oct/2013:16:58:04 -0400] cupsdRegisterPrinter(p=0x7f59247013a0(EPSON-WP-4530-Series)) D [12/Oct/2013:16:58:04 -0400] cupsdMarkDirty(P----) D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:58:04 -0400] load_ppd: Loading /etc/cups/ppd/PDF.ppd... D [12/Oct/2013:16:58:04 -0400] cupsdRegisterPrinter(p=0x7f59246fa420(PDF)) D [12/Oct/2013:16:58:04 -0400] load_ppd: Loading /var/cache/cups/Samsung-ML-2950.data... D [12/Oct/2013:16:58:04 -0400] cupsdRegisterPrinter(p=0x7f592472e440(Samsung-ML-2950)) D [12/Oct/2013:16:58:04 -0400] cupsdMarkDirty(--p--) D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [12/Oct/2013:16:58:04 -0400] Partial reload complete. D [12/Oct/2013:16:58:04 -0400] Calling FindDeviceById(cups-EPSON-WP-4530-Series) D [12/Oct/2013:16:58:04 -0400] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_EPSON_WP_4530_Series) D [12/Oct/2013:16:58:04 -0400] Using profile ID "EPSON-WP-4530-Series-Gray..". D [12/Oct/2013:16:58:04 -0400] Calling CreateProfile(EPSON-WP-4530-Series-Gray..,temp) W [12/Oct/2013:16:58:04 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-Gray..' already exists D [12/Oct/2013:16:58:04 -0400] Using profile ID "EPSON-WP-4530-Series-RGB..". D [12/Oct/2013:16:58:04 -0400] Calling CreateProfile(EPSON-WP-4530-Series-RGB..,temp) W [12/Oct/2013:16:58:04 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'EPSON-WP-4530-Series-RGB..' already exists I [12/Oct/2013:16:58:04 -0400] Registering ICC color profiles for "EPSON-WP-4530-Series". D [12/Oct/2013:16:58:04 -0400] Calling CreateDevice(cups-EPSON-WP-4530-Series,temp) D [12/Oct/2013:16:58:04 -0400] Created device "/org/freedesktop/ColorManager/devices/cups_EPSON_WP_4530_Series". D [12/Oct/2013:16:58:04 -0400] Calling FindDeviceById(cups-PDF) D [12/Oct/2013:16:58:04 -0400] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id 'cups-PDF' does not exist D [12/Oct/2013:16:58:04 -0400] Calling FindDeviceById(cups-Samsung-ML-2950) D [12/Oct/2013:16:58:04 -0400] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_Samsung_ML_2950) D [12/Oct/2013:16:58:04 -0400] Using profile ID "Samsung-ML-2950-Gray..". D [12/Oct/2013:16:58:04 -0400] Calling CreateProfile(Samsung-ML-2950-Gray..,temp) W [12/Oct/2013:16:58:04 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Samsung-ML-2950-Gray..' already exists I [12/Oct/2013:16:58:04 -0400] Registering ICC color profiles for "Samsung-ML-2950". D [12/Oct/2013:16:58:04 -0400] Calling CreateDevice(cups-Samsung-ML-2950,temp) D [12/Oct/2013:16:58:04 -0400] Created device "/org/freedesktop/ColorManager/devices/cups_Samsung_ML_2950". I [12/Oct/2013:16:58:04 -0400] Listening to [v1.::1]:631 on fd 8... I [12/Oct/2013:16:58:04 -0400] Listening to 127.0.0.1:631 on fd 10... I [12/Oct/2013:16:58:04 -0400] Listening to /var/run/cups/cups.sock:631 on fd 11... I [12/Oct/2013:16:58:04 -0400] Resuming new connection processing... D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:58:04 -0400] Notifier dbus started - PID = 3872 D [12/Oct/2013:16:58:04 -0400] Notifier dbus started - PID = 3873 D [12/Oct/2013:16:58:04 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:58:04 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:58:04 -0400] [Notifier] state=3 D [12/Oct/2013:16:58:04 -0400] PID 3782 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15. D [12/Oct/2013:16:58:04 -0400] PID 3783 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15. D [12/Oct/2013:16:58:04 -0400] [Notifier] state=3 D [12/Oct/2013:16:58:04 -0400] [Notifier] Connected to D-BUS D [12/Oct/2013:16:58:04 -0400] [Notifier] Connected to D-BUS D [12/Oct/2013:16:58:31 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:31 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:31 -0400] Report: clients=1 D [12/Oct/2013:16:58:31 -0400] Report: jobs=68 D [12/Oct/2013:16:58:31 -0400] Report: jobs-active=0 D [12/Oct/2013:16:58:31 -0400] Report: printers=3 D [12/Oct/2013:16:58:31 -0400] Report: stringpool-string-count=176450 D [12/Oct/2013:16:58:31 -0400] Report: stringpool-alloc-bytes=12904 D [12/Oct/2013:16:58:31 -0400] Report: stringpool-total-bytes=1488464 D [12/Oct/2013:16:58:31 -0400] [Client 16] POST / HTTP/1.1 D [12/Oct/2013:16:58:31 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:58:31 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:31 -0400] [Client 16] 2.0 Get-Notifications 13 D [12/Oct/2013:16:58:31 -0400] Get-Notifications / D [12/Oct/2013:16:58:31 -0400] cupsdIsAuthorized: requesting-user-name="don" D [12/Oct/2013:16:58:31 -0400] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost D [12/Oct/2013:16:58:31 -0400] [Client 16] Content-Length: 127 D [12/Oct/2013:16:58:31 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f59246fae20(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:31 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:31 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:31 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:58:31 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:58:31 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [12/Oct/2013:16:58:35 -0400] Saving printers.conf... I [12/Oct/2013:16:58:35 -0400] Generating printcap /var/run/cups/printcap... I [12/Oct/2013:16:58:35 -0400] Saving subscriptions.conf... D [12/Oct/2013:16:58:35 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [12/Oct/2013:16:58:54 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:54 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:54 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:54 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:54 -0400] [Client 16] 2.0 Get-Printer-Attributes 52 D [12/Oct/2013:16:58:54 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:58:54 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:58:54 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:58:54 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246fae50(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:54 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:54 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:54 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:54 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:54 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:54 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:58:54 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:54 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:54 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:54 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:54 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:54 -0400] [Client 16] 2.0 Get-Printer-Attributes 53 D [12/Oct/2013:16:58:54 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:58:54 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:58:54 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:58:54 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246fae50(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:54 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:54 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:54 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:54 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:54 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:54 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:58:54 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:54 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:54 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:54 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:54 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:54 -0400] [Client 16] 2.0 Get-Printer-Attributes 54 D [12/Oct/2013:16:58:54 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:58:54 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:58:54 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:58:54 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246fae50(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:54 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:54 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:54 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:54 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:54 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:54 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:58:54 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:54 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] 2.0 Get-Printer-Attributes 55 D [12/Oct/2013:16:58:55 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:58:55 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:58:55 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:58:55 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246fae50(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:55 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] 2.0 Get-Printer-Attributes 56 D [12/Oct/2013:16:58:55 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:58:55 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:58:55 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:58:55 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246fae50(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:55 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] 2.0 Get-Printer-Attributes 57 D [12/Oct/2013:16:58:55 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:58:55 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:58:55 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:58:55 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246fae50(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:55 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] 2.0 Get-Printer-Attributes 58 D [12/Oct/2013:16:58:55 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:58:55 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:58:55 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:58:55 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246fae50(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:58:55 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:58:55 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [12/Oct/2013:16:58:55 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [12/Oct/2013:16:58:55 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:58:55 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:58:55 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" I [12/Oct/2013:16:59:01 -0400] Subscription 39 has expired... D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy" D [12/Oct/2013:16:59:01 -0400] [Notifier] state=-1 D [12/Oct/2013:16:59:01 -0400] [Notifier] ippReadFile() returned IPP_ERROR! D [12/Oct/2013:16:59:01 -0400] PID 3872 (/usr/lib/cups/notifier/dbus) exited with no errors. D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 59 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 60 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 61 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 62 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 63 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 64 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 65 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 66 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 67 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Get-Printer-Attributes 68 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246e99e0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 16] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Create-Job 69 D [12/Oct/2013:16:59:01 -0400] Create-Job ipp://localhost:631/printers/PDF D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(---J-) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] add_job: requesting-user-name="don" D [12/Oct/2013:16:59:01 -0400] Adding default job-sheets values "none,none"... I [12/Oct/2013:16:59:01 -0400] [Job 97] Adding start banner page "none". D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [12/Oct/2013:16:59:01 -0400] [Job 97] Queued on "PDF" by "don". D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 182 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=182, response=0x7f5924757490(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Notifier] state=3 D [12/Oct/2013:16:59:01 -0400] [Client 16] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 16] 2.0 Send-Document 70 D [12/Oct/2013:16:59:01 -0400] Send-Document ipp://localhost:631/printers/PDF D [12/Oct/2013:16:59:01 -0400] cupsdIsAuthorized: requesting-user-name="don" D [12/Oct/2013:16:59:01 -0400] [Job 97] Auto-typing file... D [12/Oct/2013:16:59:01 -0400] [Job 97] Request file type is application/pdf. D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(---J-) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [12/Oct/2013:16:59:01 -0400] [Job 97] File of type application/pdf queued by "don". I [12/Oct/2013:16:59:01 -0400] [Job 97] Adding end banner page "none". D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(---J-) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Job 97] time-at-processing=1381611541 D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(---J-) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Job 97] Sending job to queue tagged as raw... D [12/Oct/2013:16:59:01 -0400] [Job 97] job-sheets=none,none D [12/Oct/2013:16:59:01 -0400] [Job 97] argv[0]="PDF" D [12/Oct/2013:16:59:01 -0400] [Job 97] argv[1]="97" D [12/Oct/2013:16:59:01 -0400] [Job 97] argv[2]="don" D [12/Oct/2013:16:59:01 -0400] [Job 97] argv[3]="CupsTesting" D [12/Oct/2013:16:59:01 -0400] [Job 97] argv[4]="1" D [12/Oct/2013:16:59:01 -0400] [Job 97] argv[5]="Duplex=None PageSize=Letter job-uuid=urn:uuid:9a72d3f6-a0ee-3389-6fcf-4bc0aacbaad9 job-originating-host-name=localhost time-at-creation=1381611541 time-at-processing=1381611541" D [12/Oct/2013:16:59:01 -0400] [Job 97] argv[6]="/var/spool/cups/d00097-001" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[1]="CUPS_DATADIR=/usr/share/cups" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[6]="CUPS_SERVERROOT=/etc/cups" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[7]="CUPS_STATEDIR=/var/run/cups" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[8]="HOME=/var/spool/cups/tmp" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[10]="SERVER_ADMIN=root@don-NY553AAR-ABA-e9220y" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[11]="SOFTWARE=CUPS/1.7rc1" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[12]="TMPDIR=/var/spool/cups/tmp" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[13]="USER=root" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[14]="CUPS_MAX_MESSAGE=2047" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[16]="CUPS_ENCRYPTION=IfRequested" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[17]="IPP_PORT=631" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[18]="CHARSET=utf-8" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[19]="LANG=en_US.UTF-8" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[20]="PPD=/etc/cups/ppd/PDF.ppd" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[21]="RIP_MAX_CACHE=128m" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[22]="CONTENT_TYPE=application/pdf" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[23]="DEVICE_URI=cups-pdf:/" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[24]="PRINTER_INFO=PDF" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[25]="PRINTER_LOCATION=" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[26]="PRINTER=PDF" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[27]="PRINTER_STATE_REASONS=none" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[28]="CUPS_FILETYPE=document" D [12/Oct/2013:16:59:01 -0400] [Job 97] envp[29]="AUTH_I****" I [12/Oct/2013:16:59:01 -0400] [Job 97] Started backend /usr/lib/cups/backend/cups-pdf (PID 3892) D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 16] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 16] Content-Length: 174 D [12/Oct/2013:16:59:01 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174, response=0x7f59246ec4b0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 16] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [12/Oct/2013:16:59:01 -0400] [Notifier] state=3 D [12/Oct/2013:16:59:01 -0400] [Notifier] state=3 D [12/Oct/2013:16:59:01 -0400] [Client 19] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 19] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 19] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 19] 2.0 Get-Printer-Attributes 71 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 19] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f59246ec4b0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 19] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 19] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 19] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 19] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 19] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 19] POST /printers/PDF HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 19] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 19] 2.0 Get-Printer-Attributes 72 D [12/Oct/2013:16:59:01 -0400] Get-Printer-Attributes ipp://localhost/printers/PDF D [12/Oct/2013:16:59:01 -0400] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 19] Content-Length: 6114 D [12/Oct/2013:16:59:01 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6114, response=0x7f5924700dc0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 19] GET /printers/PDF.ppd HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 19] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 19] Closing because Keep-Alive disabled D [12/Oct/2013:16:59:01 -0400] [Client 19] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [12/Oct/2013:16:59:01 -0400] [Job 97] PID 3892 (/usr/lib/cups/backend/cups-pdf) exited with no errors. D [12/Oct/2013:16:59:01 -0400] [Job 97] time-at-completed=1381611541 D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [12/Oct/2013:16:59:01 -0400] [Job 97] Job completed. D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(---J-) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(---J-) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] cupsdMarkDirty(---J-) D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] [Notifier] state=3 D [12/Oct/2013:16:59:01 -0400] [Notifier] state=3 D [12/Oct/2013:16:59:01 -0400] [Client 17] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 17] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 17] POST / HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 17] 2.0 Get-Notifications 14 D [12/Oct/2013:16:59:01 -0400] Get-Notifications / D [12/Oct/2013:16:59:01 -0400] cupsdIsAuthorized: requesting-user-name="don" D [12/Oct/2013:16:59:01 -0400] [Client 17] Returning IPP successful-ok for Get-Notifications (/) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 17] Content-Length: 959 D [12/Oct/2013:16:59:01 -0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=959, response=0x7f5924752090(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:59:01 -0400] [Client 17] Closing connection. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] Accepted from localhost (Domain) D [12/Oct/2013:16:59:01 -0400] [Client 17] Waiting for request. D [12/Oct/2013:16:59:01 -0400] [Client 17] POST / HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 17] 2.0 CUPS-Get-Printers 15 D [12/Oct/2013:16:59:01 -0400] CUPS-Get-Printers D [12/Oct/2013:16:59:01 -0400] [Client 17] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 17] Content-Length: 1260 D [12/Oct/2013:16:59:01 -0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1260, response=0x7f59247521c0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] POST / HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 17] 2.0 CUPS-Get-Classes 16 D [12/Oct/2013:16:59:01 -0400] CUPS-Get-Classes D [12/Oct/2013:16:59:01 -0400] [Client 17] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 17] Content-Length: 75 D [12/Oct/2013:16:59:01 -0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f59247c04f0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] POST / HTTP/1.1 D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:01 -0400] [Client 17] No authentication data provided. D [12/Oct/2013:16:59:01 -0400] [Client 17] 2.0 CUPS-Get-Default 17 D [12/Oct/2013:16:59:01 -0400] CUPS-Get-Default D [12/Oct/2013:16:59:01 -0400] [Client 17] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [12/Oct/2013:16:59:01 -0400] [Client 17] Content-Length: 8633 D [12/Oct/2013:16:59:01 -0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8633, response=0x7f59247521c0(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:01 -0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:01 -0400] [Client 17] Waiting for request. D [12/Oct/2013:16:59:01 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:02 -0400] [Job 97] Unloading... D [12/Oct/2013:16:59:25 -0400] [Client 19] Accepted from localhost (Domain) D [12/Oct/2013:16:59:25 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:25 -0400] [Client 19] POST / HTTP/1.1 D [12/Oct/2013:16:59:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:25 -0400] [Client 19] No authentication data provided. D [12/Oct/2013:16:59:25 -0400] [Client 19] 2.0 Renew-Subscription 18 D [12/Oct/2013:16:59:25 -0400] Renew-Subscription / D [12/Oct/2013:16:59:25 -0400] [Client 19] Returning HTTP Unauthorized for Renew-Subscription (/) from localhost D [12/Oct/2013:16:59:25 -0400] [Client 19] WWW-Authenticate: Basic realm="CUPS", trc="y" D [12/Oct/2013:16:59:25 -0400] [Client 19] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:59:25 -0400] [Client 19] Closing connection. D [12/Oct/2013:16:59:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:25 -0400] [Client 19] Accepted from localhost (Domain) D [12/Oct/2013:16:59:25 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:25 -0400] [Client 20] Accepted from localhost (Domain) D [12/Oct/2013:16:59:25 -0400] [Client 20] Waiting for request. D [12/Oct/2013:16:59:25 -0400] [Client 19] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:59:25 -0400] [Client 19] Closing connection. D [12/Oct/2013:16:59:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:59:25 -0400] [Client 19] Accepted from localhost (Domain) D [12/Oct/2013:16:59:25 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:25 -0400] [Client 20] HTTP_STATE_WAITING Closing on EOF D [12/Oct/2013:16:59:25 -0400] [Client 20] Closing connection. D [12/Oct/2013:16:59:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [12/Oct/2013:16:59:25 -0400] [Client 19] POST / HTTP/1.1 D [12/Oct/2013:16:59:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [12/Oct/2013:16:59:25 -0400] [Client 19] Authorized as don using PeerCred D [12/Oct/2013:16:59:25 -0400] [Client 19] 2.0 Renew-Subscription 18 D [12/Oct/2013:16:59:25 -0400] Renew-Subscription / D [12/Oct/2013:16:59:25 -0400] cupsdIsAuthorized: username="don" D [12/Oct/2013:16:59:25 -0400] cupsdMarkDirty(----S) D [12/Oct/2013:16:59:25 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [12/Oct/2013:16:59:25 -0400] [Client 19] Returning IPP successful-ok for Renew-Subscription (/) from localhost D [12/Oct/2013:16:59:25 -0400] [Client 19] Content-Length: 106 D [12/Oct/2013:16:59:25 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=106, response=0x7f59246f9150(IPP_IDLE), pipe_pid=0, file=-1 D [12/Oct/2013:16:59:25 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [12/Oct/2013:16:59:25 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0 D [12/Oct/2013:16:59:25 -0400] [Client 19] Waiting for request. D [12/Oct/2013:16:59:25 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [12/Oct/2013:16:59:32 -0400] Saving job.cache... I [12/Oct/2013:16:59:32 -0400] Saving subscriptions.conf... D [12/Oct/2013:16:59:32 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [12/Oct/2013:16:59:32 -0400] Report: clients=3 D [12/Oct/2013:16:59:32 -0400] Report: jobs=69 D [12/Oct/2013:16:59:32 -0400] Report: jobs-active=0 D [12/Oct/2013:16:59:32 -0400] Report: printers=3 D [12/Oct/2013:16:59:32 -0400] Report: stringpool-string-count=177023 D [12/Oct/2013:16:59:32 -0400] Report: stringpool-alloc-bytes=12936 D [12/Oct/2013:16:59:32 -0400] Report: stringpool-total-bytes=1502272