Attachment 936649 Details for Bug 1140807 – /var/log/cups/error_log

/var/log/cups/error_log

error_log (text/plain), 205.60 KB, created by Patrick on 2014-09-11 18:04:10 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Patrick

Created: 2014-09-11 18:04:10 UTC

Size: 205.60 KB

patch

obsolete

>W [09/Sep/2014:23:37:47 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>W [09/Sep/2014:23:37:47 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>W [10/Sep/2014:19:53:39 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>W [10/Sep/2014:19:53:39 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>E [11/Sep/2014:09:52:08 +0200] [Client 14] pam_authenticate() returned 7 (Authentication failure)
>W [11/Sep/2014:09:52:22 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>W [11/Sep/2014:09:52:22 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>I [11/Sep/2014:10:36:29 +0200] Listening to [v1.::1]:631 (IPv6)
>I [11/Sep/2014:10:36:29 +0200] Listening to 127.0.0.1:631 (IPv4)
>I [11/Sep/2014:10:36:29 +0200] Listening to /var/run/cups/cups.sock (Domain)
>E [11/Sep/2014:10:36:29 +0200] Unknown directive JobPrivateAccess on line 84 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:36:29 +0200] Unknown directive JobPrivateValues on line 85 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:36:29 +0200] Unknown directive SubscriptionPrivateAccess on line 86 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:36:29 +0200] Unknown directive SubscriptionPrivateValues on line 87 of /etc/cups/cupsd.conf.
>I [11/Sep/2014:10:36:29 +0200] Remote access is disabled.
>D [11/Sep/2014:10:36:29 +0200] Added auto ServerAlias teucidide
>I [11/Sep/2014:10:36:29 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
>I [11/Sep/2014:10:36:29 +0200] Configured for up to 100 clients.
>I [11/Sep/2014:10:36:29 +0200] Allowing up to 100 client connections per host.
>I [11/Sep/2014:10:36:29 +0200] Using policy "default" as the default.
>D [11/Sep/2014:10:36:29 +0200] load_ppd: Loading /var/cache/cups/Cups-PDF.data...
>D [11/Sep/2014:10:36:29 +0200] cupsdRegisterPrinter(p=0x7f4fa6a7eb50(Cups-PDF))
>D [11/Sep/2014:10:36:29 +0200] load_ppd: Loading /var/cache/cups/HP_LaserJet_P1005.data...
>D [11/Sep/2014:10:36:29 +0200] cupsdRegisterPrinter(p=0x7f4fa6a73450(HP_LaserJet_P1005))
>D [11/Sep/2014:10:36:29 +0200] cupsdMarkDirty(--p--)
>D [11/Sep/2014:10:36:29 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
>I [11/Sep/2014:10:36:29 +0200] Partial reload complete.
>D [11/Sep/2014:10:36:29 +0200] systemd_checkin: Matched existing listener /var/run/cups/cups.sock with fd 3...
>D [11/Sep/2014:10:36:29 +0200] Calling FindDeviceById(cups-Cups-PDF)
>D [11/Sep/2014:10:36:29 +0200] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_Cups_PDF)
>D [11/Sep/2014:10:36:29 +0200] Using profile ID "Cups-PDF-Gray..".
>D [11/Sep/2014:10:36:29 +0200] Calling CreateProfile(Cups-PDF-Gray..,temp)
>W [11/Sep/2014:10:36:29 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-Gray..' already exists
>D [11/Sep/2014:10:36:29 +0200] Using profile ID "Cups-PDF-RGB..".
>D [11/Sep/2014:10:36:29 +0200] Calling CreateProfile(Cups-PDF-RGB..,temp)
>W [11/Sep/2014:10:36:29 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-RGB..' already exists
>I [11/Sep/2014:10:36:29 +0200] Registering ICC color profiles for "Cups-PDF".
>D [11/Sep/2014:10:36:29 +0200] Calling CreateDevice(cups-Cups-PDF,temp)
>D [11/Sep/2014:10:36:29 +0200] Created device "/org/freedesktop/ColorManager/devices/cups_Cups_PDF".
>D [11/Sep/2014:10:36:29 +0200] Calling FindDeviceById(cups-HP_LaserJet_P1005)
>D [11/Sep/2014:10:36:29 +0200] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005)
>D [11/Sep/2014:10:36:29 +0200] Using profile ID "HP_LaserJet_P1005-Gray..".
>D [11/Sep/2014:10:36:29 +0200] Calling CreateProfile(HP_LaserJet_P1005-Gray..,temp)
>W [11/Sep/2014:10:36:29 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>D [11/Sep/2014:10:36:29 +0200] Using profile ID "HP_LaserJet_P1005-RGB..".
>D [11/Sep/2014:10:36:29 +0200] Calling CreateProfile(HP_LaserJet_P1005-RGB..,temp)
>W [11/Sep/2014:10:36:29 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>I [11/Sep/2014:10:36:29 +0200] Registering ICC color profiles for "HP_LaserJet_P1005".
>D [11/Sep/2014:10:36:29 +0200] Calling CreateDevice(cups-HP_LaserJet_P1005,temp)
>D [11/Sep/2014:10:36:29 +0200] Created device "/org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005".
>I [11/Sep/2014:10:36:29 +0200] Listening to [v1.::1]:631 on fd 10...
>I [11/Sep/2014:10:36:29 +0200] Listening to 127.0.0.1:631 on fd 11...
>I [11/Sep/2014:10:36:29 +0200] Listening to /var/run/cups/cups.sock:631 on fd 3...
>I [11/Sep/2014:10:36:29 +0200] Resuming new connection processing...
>D [11/Sep/2014:10:36:29 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:10:36:29 +0200] Discarding unused server-restarted event...
>D [11/Sep/2014:10:36:30 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:36:30 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:10:36:30 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:10:36:30 +0200] Report: clients=1
>D [11/Sep/2014:10:36:30 +0200] Report: jobs=38
>D [11/Sep/2014:10:36:30 +0200] Report: jobs-active=1
>D [11/Sep/2014:10:36:30 +0200] Report: printers=2
>D [11/Sep/2014:10:36:30 +0200] Report: stringpool-string-count=5116
>D [11/Sep/2014:10:36:30 +0200] Report: stringpool-alloc-bytes=13344
>D [11/Sep/2014:10:36:30 +0200] Report: stringpool-total-bytes=98272
>D [11/Sep/2014:10:36:30 +0200] PID 3760 (/usr/lib/cups/notifier/dbus) exited with no errors.
>I [11/Sep/2014:10:37:00 +0200] Generating printcap /etc/printcap...
>D [11/Sep/2014:10:37:00 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>D [11/Sep/2014:10:37:22 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] GET /admin/log/error_log HTTP/1.1
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] No authentication data provided.
>I [11/Sep/2014:10:37:22 +0200] [Client 15] Files/directories such as "/var/log/cups/error_log" must be world-readable.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Closing because Keep-Alive disabled
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Closing connection.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:10:37:22 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:22 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:22 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Closing connection.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:22 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] GET /admin/log/error_log HTTP/1.1
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] No authentication data provided.
>I [11/Sep/2014:10:37:22 +0200] [Client 15] Files/directories such as "/var/log/cups/error_log" must be world-readable.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Closing because Keep-Alive disabled
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Closing connection.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:10:37:22 +0200] [Client 14] GET /admin/log/error_log HTTP/1.1
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] [Client 14] No authentication data provided.
>I [11/Sep/2014:10:37:22 +0200] [Client 14] Files/directories such as "/var/log/cups/error_log" must be world-readable.
>D [11/Sep/2014:10:37:22 +0200] [Client 14] Closing because Keep-Alive disabled
>D [11/Sep/2014:10:37:22 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:10:37:22 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:22 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:22 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] 1.1 Get-Jobs 19
>D [11/Sep/2014:10:37:22 +0200] Get-Jobs ipp://localhost/printers/
>D [11/Sep/2014:10:37:22 +0200] [Job 105] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Content-Length: 166
>D [11/Sep/2014:10:37:22 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=166, response=0x7f4fa6b2cb30(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:22 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] 1.1 Get-Jobs 20
>D [11/Sep/2014:10:37:22 +0200] Get-Jobs ipp://localhost/printers/
>D [11/Sep/2014:10:37:22 +0200] [Job 25] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 26] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 27] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 28] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 29] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 30] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 31] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 32] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 33] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 34] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 35] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 37] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 42] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 44] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 94] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 95] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 96] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 97] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 98] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 99] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 100] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 101] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 102] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 103] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 104] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 106] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 117] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 118] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 124] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 127] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 144] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 145] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 146] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 147] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 148] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 149] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Job 150] Loading attributes...
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Content-Length: 3527
>D [11/Sep/2014:10:37:22 +0200] [Job 25] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 26] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 27] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 28] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 29] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 30] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 31] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 32] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 33] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 34] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 35] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 37] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 42] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 44] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 94] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 95] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 96] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 97] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 98] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 99] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 100] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 101] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 102] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 103] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 104] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 106] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 117] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 118] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 124] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Job 127] Removing document files.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3527, response=0x7f4fa6af3b10(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:22 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:22 +0200] [Client 15] 1.1 Create-Printer-Subscriptions 21
>D [11/Sep/2014:10:37:22 +0200] Create-Printer-Subscriptions /
>D [11/Sep/2014:10:37:22 +0200] create_subscriptions(con=0x7f4fa6a632f0(15), uri="/")
>D [11/Sep/2014:10:37:22 +0200] pullmethod="ippget"
>D [11/Sep/2014:10:37:22 +0200] notify-lease-duration=86400
>D [11/Sep/2014:10:37:22 +0200] notify-time-interval=0
>D [11/Sep/2014:10:37:22 +0200] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")
>D [11/Sep/2014:10:37:22 +0200] Added subscription #182 for server.
>D [11/Sep/2014:10:37:22 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Content-Length: 107
>D [11/Sep/2014:10:37:22 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x7f4fa6b2cb30(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:22 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:22 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:22 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:23 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:23 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:23 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:23 +0200] [Client 15] 1.1 Get-Notifications 22
>D [11/Sep/2014:10:37:23 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:23 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:23 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:23 +0200] [Client 15] Content-Length: 127
>D [11/Sep/2014:10:37:23 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6af3b10(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:23 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:23 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:23 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:23 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:29 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:29 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:29 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:29 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:10:37:29 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:29 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:10:37:29 +0200] [Client 16] 1.1 Get-Notifications 23
>D [11/Sep/2014:10:37:29 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:29 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:29 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:29 +0200] [Client 16] Content-Length: 127
>D [11/Sep/2014:10:37:29 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6a69bb0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:29 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:29 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:29 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:29 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:29 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:29 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:29 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:30 +0200] Report: clients=2
>D [11/Sep/2014:10:37:30 +0200] Report: jobs=38
>D [11/Sep/2014:10:37:30 +0200] Report: jobs-active=1
>D [11/Sep/2014:10:37:30 +0200] Report: printers=2
>D [11/Sep/2014:10:37:30 +0200] Report: stringpool-string-count=6780
>D [11/Sep/2014:10:37:30 +0200] Report: stringpool-alloc-bytes=17376
>D [11/Sep/2014:10:37:30 +0200] Report: stringpool-total-bytes=131032
>D [11/Sep/2014:10:37:41 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:41 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:41 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] [Client 16] POST /printers/HP%5FLaserJet%5FP1005 HTTP/1.1
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:10:37:41 +0200] [Client 16] 1.1 Print-Job 24
>D [11/Sep/2014:10:37:41 +0200] Print-Job ipp://localhost/printers/HP%5FLaserJet%5FP1005
>D [11/Sep/2014:10:37:41 +0200] [Job ???] Auto-typing file...
>I [11/Sep/2014:10:37:41 +0200] [Job ???] Request file type is application/vnd.cups-pdf-banner.
>D [11/Sep/2014:10:37:41 +0200] add_job: setting context of job to UNKNOWN SL
>D [11/Sep/2014:10:37:41 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:41 +0200] add_job: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:41 +0200] Adding default job-sheets values "none,none"...
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Adding start banner page "none".
>D [11/Sep/2014:10:37:41 +0200] Notifier dbus started - PID = 8517
>D [11/Sep/2014:10:37:41 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:41 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Adding end banner page "none".
>I [11/Sep/2014:10:37:41 +0200] [Job 151] File of type application/vnd.cups-pdf-banner queued by "pdupre".
>D [11/Sep/2014:10:37:41 +0200] [Job 151] hold_until=0
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Queued on "HP_LaserJet_P1005" by "pdupre".
>D [11/Sep/2014:10:37:41 +0200] [Job 151] time-at-processing=1410424661
>D [11/Sep/2014:10:37:41 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:41 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] 4 filters for job:
>D [11/Sep/2014:10:37:41 +0200] [Job 151] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32)
>D [11/Sep/2014:10:37:41 +0200] [Job 151] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
>D [11/Sep/2014:10:37:41 +0200] [Job 151] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
>D [11/Sep/2014:10:37:41 +0200] [Job 151] hpcups (application/vnd.cups-raster to printer/HP_LaserJet_P1005, cost 0)
>D [11/Sep/2014:10:37:41 +0200] [Job 151] job-sheets=none,none
>D [11/Sep/2014:10:37:41 +0200] [Job 151] argv[0]="HP_LaserJet_P1005"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] argv[1]="151"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] argv[2]="pdupre"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] argv[3]="Test Page"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] argv[4]="1"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] argv[5]="job-uuid=urn:uuid:83cd38f1-0a49-320a-4922-6fc741f815c6 job-originating-host-name=localhost time-at-creation=1410424661 time-at-processing=1410424661"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] argv[6]="/var/spool/cups/d00151-001"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[8]="HOME=/var/spool/cups/tmp"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[10]="SERVER_ADMIN=root@teucidide"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[11]="SOFTWARE=CUPS/1.7.4"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[12]="TMPDIR=/var/spool/cups/tmp"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[13]="USER=root"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[14]="CUPS_MAX_MESSAGE=2047"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[16]="CUPS_ENCRYPTION=IfRequested"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[17]="IPP_PORT=631"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[18]="CHARSET=utf-8"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[19]="LANG=en_US.UTF-8"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_P1005.ppd"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[21]="RIP_MAX_CACHE=128m"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_P1005?serial=BB07BPM"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[24]="PRINTER_INFO=HP_LaserJet_P1005"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[25]="PRINTER_LOCATION="
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[26]="PRINTER=HP_LaserJet_P1005"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[27]="PRINTER_STATE_REASONS=none"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[28]="CUPS_FILETYPE=document"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[29]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_P1005"
>D [11/Sep/2014:10:37:41 +0200] [Job 151] envp[30]="AUTH_I****"
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Started filter /usr/lib/cups/filter/bannertopdf (PID 8518)
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Started filter /usr/lib/cups/filter/pdftopdf (PID 8519)
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Started filter /usr/lib/cups/filter/gstoraster (PID 8520)
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Started filter /usr/lib/cups/filter/hpcups (PID 8521)
>I [11/Sep/2014:10:37:41 +0200] [Job 151] Started backend /usr/lib/cups/backend/hp (PID 8522)
>D [11/Sep/2014:10:37:41 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Client 16] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/HP%5FLaserJet%5FP1005) from localhost
>D [11/Sep/2014:10:37:41 +0200] [Client 16] Content-Length: 175
>D [11/Sep/2014:10:37:41 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=175, response=0x7f4fa6a69bb0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:41 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:41 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:41 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:41 +0200] [Notifier] Connected to D-BUS
>D [11/Sep/2014:10:37:41 +0200] [Notifier] JobCreated
>D [11/Sep/2014:10:37:41 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:41 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:41 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:41 +0200] [Client 22] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:41 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:41 +0200] [Client 22] 1.1 Get-Notifications 109
>D [11/Sep/2014:10:37:41 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:41 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:41 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:41 +0200] [Client 22] Content-Length: 1623
>D [11/Sep/2014:10:37:41 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1623, response=0x7f4fa6a68b40(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:41 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:41 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:41 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:41 +0200] [Notifier] JobState
>D [11/Sep/2014:10:37:41 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:41 +0200] [Client 23] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:41 +0200] [Client 23] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] [Client 23] POST / HTTP/1.1
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Client 23] No authentication data provided.
>D [11/Sep/2014:10:37:41 +0200] [Client 23] 1.1 Get-Job-Attributes 110
>D [11/Sep/2014:10:37:41 +0200] Get-Job-Attributes ipp://localhost/jobs/151
>D [11/Sep/2014:10:37:41 +0200] [Client 23] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/151) from localhost
>D [11/Sep/2014:10:37:41 +0200] [Client 23] Content-Length: 112
>D [11/Sep/2014:10:37:41 +0200] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=112, response=0x7f4fa6a6c910(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:41 +0200] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:41 +0200] [Client 23] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:41 +0200] [Client 23] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:41 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:41 +0200] [Client 24] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:41 +0200] [Client 24] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] [Client 24] POST / HTTP/1.1
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:41 +0200] [Client 24] No authentication data provided.
>D [11/Sep/2014:10:37:41 +0200] [Client 24] 1.1 Get-Job-Attributes 111
>D [11/Sep/2014:10:37:41 +0200] Get-Job-Attributes ipp://localhost/jobs/151
>D [11/Sep/2014:10:37:41 +0200] [Client 24] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/151) from localhost
>D [11/Sep/2014:10:37:41 +0200] [Client 24] Content-Length: 112
>D [11/Sep/2014:10:37:41 +0200] [Client 24] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=112, response=0x7f4fa6adaf20(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:41 +0200] [Client 24] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:41 +0200] [Client 24] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:41 +0200] [Client 24] Waiting for request.
>D [11/Sep/2014:10:37:41 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] STATE: -marker-supply-low-warning
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] PDF template file doesn't have form. It's okay.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 Get-Notifications 112
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 127
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6a69be0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 Get-Notifications 113
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 127
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6a67bb0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] PID 8518 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
>D [11/Sep/2014:10:37:42 +0200] [Job 151] PID 8519 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Calling FindDeviceById(cups-HP_LaserJet_P1005)
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Found device /org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
>D [11/Sep/2014:10:37:42 +0200] [Job 151] PPD uses qualifier 'Gray.Plain.'
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Calling FindDeviceById(cups-HP_LaserJet_P1005)
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Found device /org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Calling GetProfileForQualifiers(Gray.Plain....)
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Failed to send: org.freedesktop.ColorManager.Device.NothingMatched:nothing matched expression 'Gray.Plain.,Gray.Plain.*,Gray.*.,Gray.*.*,*'
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Failed to get profile filename for cups-HP_LaserJet_P1005
>I [11/Sep/2014:10:37:42 +0200] [Job 151] no profiles specified in PPD
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -sMediaType=Plain -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=1 -dcupsColorOrder=0 -dcupsColorSpace=3 -dcupsRowCount=1 -dcupsRowStep=2 -dcupsInteger0=9 -dcupsInteger1=600 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c '<</.HWMargins[13.560000 13.500000 13.559998 13.500000] /Margins[0 0]>>setpagedevice' -f -_
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[8]="HOME=/var/spool/cups/tmp"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[10]="SERVER_ADMIN=root@teucidide"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[11]="SOFTWARE=CUPS/1.7.4"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[12]="TMPDIR=/var/spool/cups/tmp"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[13]="USER=root"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[14]="CUPS_MAX_MESSAGE=2047"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[16]="CUPS_ENCRYPTION=IfRequested"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[17]="IPP_PORT=631"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[18]="CHARSET=utf-8"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[19]="LANG=en_US.UTF-8"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_P1005.ppd"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[21]="RIP_MAX_CACHE=128m"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_P1005?serial=BB07BPM"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[24]="PRINTER_INFO=HP_LaserJet_P1005"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[25]="PRINTER_LOCATION="
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[26]="PRINTER=HP_LaserJet_P1005"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[27]="PRINTER_STATE_REASONS=none"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[28]="CUPS_FILETYPE=document"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[29]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_P1005"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] envp[30]="AUTH_INFO_REQUIRED=none"
>D [11/Sep/2014:10:37:42 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:42 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 Get-Notifications 114
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6b2e820(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 CUPS-Get-Printers 115
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b0f870(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>I [11/Sep/2014:10:37:42 +0200] [Job 151] Start rendering...
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Set job-printer-state-message to "Start rendering...", current level=INFO
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>I [11/Sep/2014:10:37:42 +0200] [Job 151] Processing page 1...
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] Set job-printer-state-message to "Processing page 1...", current level=INFO
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:42 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 Get-Notifications 116
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 993
>D [11/Sep/2014:10:37:42 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:42 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=993, response=0x7f4fa6b1c380(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 CUPS-Get-Printers 117
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b1c170(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 CUPS-Get-Printers 118
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b1c380(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 Get-Notifications 119
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 127
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6b1c170(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 25] 1.1 Get-Notifications 25
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Content-Length: 1873
>D [11/Sep/2014:10:37:42 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1873, response=0x7f4fa6b1c300(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Closing connection.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 15] 1.1 Get-Notifications 26
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Content-Length: 2778
>D [11/Sep/2014:10:37:42 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2778, response=0x7f4fa6b1c300(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] STATE: +connecting-to-device
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:42 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 Get-Notifications 120
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 576
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=576, response=0x7f4fa6b3e9e0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 CUPS-Get-Printers 121
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 2108
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2108, response=0x7f4fa6b1c3c0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] PAGE: 1 1
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] STATE: -connecting-to-device
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:42 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 Get-Notifications 122
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6b1d480(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 22] 1.1 CUPS-Get-Printers 123
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:10:37:42 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b1d210(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Job 151] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
>D [11/Sep/2014:10:37:42 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 25] 1.1 CUPS-Get-Printers 27
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Content-Length: 855
>D [11/Sep/2014:10:37:42 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=855, response=0x7f4fa6b1d380(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 25] 1.1 CUPS-Get-Classes 28
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Classes
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:10:37:42 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b1d240(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 16] 1.1 Get-Notifications 29
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Content-Length: 1009
>D [11/Sep/2014:10:37:42 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1009, response=0x7f4fa6b1d470(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:42 +0200] [Client 26] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:42 +0200] [Client 26] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:42 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 15] 1.1 Get-Notifications 30
>D [11/Sep/2014:10:37:42 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:42 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Content-Length: 673
>D [11/Sep/2014:10:37:42 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=673, response=0x7f4fa6b1d470(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 25] 1.1 CUPS-Get-Printers 31
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Content-Length: 855
>D [11/Sep/2014:10:37:42 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=855, response=0x7f4fa6b1d270(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:42 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:10:37:42 +0200] [Client 25] 1.1 CUPS-Get-Classes 32
>D [11/Sep/2014:10:37:42 +0200] CUPS-Get-Classes
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:10:37:42 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b1d470(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:42 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:42 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>I [11/Sep/2014:10:37:47 +0200] [Job 151] Processing page 2...
>D [11/Sep/2014:10:37:47 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Job 151] Set job-printer-state-message to "Processing page 2...", current level=INFO
>D [11/Sep/2014:10:37:47 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:47 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:47 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 22] 1.1 Get-Notifications 124
>D [11/Sep/2014:10:37:47 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:47 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:10:37:47 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6b11f70(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 22] 1.1 CUPS-Get-Printers 125
>D [11/Sep/2014:10:37:47 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:10:37:47 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b11d00(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>I [11/Sep/2014:10:37:47 +0200] [Job 151] Rendering completed
>D [11/Sep/2014:10:37:47 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Job 151] Set job-printer-state-message to "Rendering completed", current level=INFO
>D [11/Sep/2014:10:37:47 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Notifier] state=3
>D [11/Sep/2014:10:37:47 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:10:37:47 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 22] 1.1 Get-Notifications 126
>D [11/Sep/2014:10:37:47 +0200] Get-Notifications /printers/
>D [11/Sep/2014:10:37:47 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:10:37:47 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6b12f70(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 22] 1.1 CUPS-Get-Printers 127
>D [11/Sep/2014:10:37:47 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:10:37:47 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b12990(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Job 151] PID 8520 (/usr/lib/cups/filter/gstoraster) exited with no errors.
>D [11/Sep/2014:10:37:47 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:47 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:47 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 16] 1.1 Get-Notifications 33
>D [11/Sep/2014:10:37:47 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:47 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:47 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 16] Content-Length: 993
>D [11/Sep/2014:10:37:47 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=993, response=0x7f4fa6b122f0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:47 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 15] 1.1 Get-Notifications 34
>D [11/Sep/2014:10:37:47 +0200] Get-Notifications /
>D [11/Sep/2014:10:37:47 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:47 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 15] Content-Length: 1190
>D [11/Sep/2014:10:37:47 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1190, response=0x7f4fa6b133a0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 25] 1.1 CUPS-Get-Printers 35
>D [11/Sep/2014:10:37:47 +0200] CUPS-Get-Printers
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Content-Length: 854
>D [11/Sep/2014:10:37:47 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=854, response=0x7f4fa6b122f0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:10:37:47 +0200] [Client 25] 1.1 CUPS-Get-Classes 36
>D [11/Sep/2014:10:37:47 +0200] CUPS-Get-Classes
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:10:37:47 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b133a0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:47 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:47 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:10:37:47 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:47 +0200] [Job 151] PID 8521 (/usr/lib/cups/filter/hpcups) exited with no errors.
>I [11/Sep/2014:10:37:53 +0200] Saving job.cache...
>I [11/Sep/2014:10:37:53 +0200] Saving subscriptions.conf...
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:10:37:54 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:54 +0200] [Client 15] 1.1 Get-Job-Attributes 37
>D [11/Sep/2014:10:37:54 +0200] Get-Job-Attributes ipp://localhost/jobs/151
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/151) from localhost
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Content-Length: 838
>D [11/Sep/2014:10:37:54 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=838, response=0x7f4fa6b12230(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:54 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
>D [11/Sep/2014:10:37:54 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:10:37:54 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:10:37:54 +0200] [Client 15] 1.1 Cancel-Subscription 38
>D [11/Sep/2014:10:37:54 +0200] Cancel-Subscription /
>D [11/Sep/2014:10:37:54 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:10:37:54 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and printing jobs"
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Content-Length: 75
>D [11/Sep/2014:10:37:54 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b128e0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:10:37:54 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:54 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:54 +0200] [Client 16] PUT /admin/conf/cupsd.conf HTTP/1.1
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:10:37:54 +0200] cupsdIsAuthorized: username=""
>D [11/Sep/2014:10:37:54 +0200] [Client 16] WWW-Authenticate: Basic realm="CUPS", trc="y"
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:54 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:54 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:54 +0200] [Client 27] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:54 +0200] [Client 27] Waiting for request.
>D [11/Sep/2014:10:37:54 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:10:37:54 +0200] [Client 27] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:10:37:54 +0200] [Client 27] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 16] PUT /admin/conf/cupsd.conf HTTP/1.1
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Authorized as root using PeerCred
>D [11/Sep/2014:10:37:54 +0200] cupsdIsAuthorized: username="root"
>I [11/Sep/2014:10:37:54 +0200] Installing config file "/etc/cups/cupsd.conf"...
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 15] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 22] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 23] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 24] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 25] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 26] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:10:37:54 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>I [11/Sep/2014:10:37:54 +0200] Saving subscriptions.conf...
>D [11/Sep/2014:10:37:54 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
>E [11/Sep/2014:10:37:54 +0200] Unknown directive JobPrivateAccess on line 84 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:37:54 +0200] Unknown directive JobPrivateValues on line 85 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:37:54 +0200] Unknown directive SubscriptionPrivateAccess on line 86 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:37:54 +0200] Unknown directive SubscriptionPrivateValues on line 87 of /etc/cups/cupsd.conf.
>W [11/Sep/2014:10:37:54 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-Gray..' already exists
>W [11/Sep/2014:10:37:54 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-RGB..' already exists
>W [11/Sep/2014:10:37:54 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>W [11/Sep/2014:10:37:54 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>E [11/Sep/2014:10:55:25 +0200] Unknown directive JobPrivateAccess on line 83 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:55:25 +0200] Unknown directive JobPrivateValues on line 84 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:55:25 +0200] Unknown directive SubscriptionPrivateAccess on line 85 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:10:55:25 +0200] Unknown directive SubscriptionPrivateValues on line 86 of /etc/cups/cupsd.conf.
>W [11/Sep/2014:10:55:25 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-Gray..' already exists
>W [11/Sep/2014:10:55:25 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-RGB..' already exists
>W [11/Sep/2014:10:55:25 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>W [11/Sep/2014:10:55:25 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>I [11/Sep/2014:17:08:17 +0200] Listening to [v1.::1]:631 (IPv6)
>I [11/Sep/2014:17:08:17 +0200] Listening to 127.0.0.1:631 (IPv4)
>I [11/Sep/2014:17:08:17 +0200] Listening to /var/run/cups/cups.sock (Domain)
>E [11/Sep/2014:17:08:17 +0200] Unknown directive JobPrivateAccess on line 84 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:08:17 +0200] Unknown directive JobPrivateValues on line 85 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:08:17 +0200] Unknown directive SubscriptionPrivateAccess on line 86 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:08:17 +0200] Unknown directive SubscriptionPrivateValues on line 87 of /etc/cups/cupsd.conf.
>I [11/Sep/2014:17:08:17 +0200] Remote access is disabled.
>D [11/Sep/2014:17:08:17 +0200] Added auto ServerAlias teucidide
>I [11/Sep/2014:17:08:17 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
>I [11/Sep/2014:17:08:17 +0200] Configured for up to 100 clients.
>I [11/Sep/2014:17:08:17 +0200] Allowing up to 100 client connections per host.
>I [11/Sep/2014:17:08:17 +0200] Using policy "default" as the default.
>D [11/Sep/2014:17:08:17 +0200] load_ppd: Loading /var/cache/cups/Cups-PDF.data...
>D [11/Sep/2014:17:08:17 +0200] cupsdRegisterPrinter(p=0x7f4fa6a7eb50(Cups-PDF))
>D [11/Sep/2014:17:08:17 +0200] load_ppd: Loading /var/cache/cups/HP_LaserJet_P1005.data...
>D [11/Sep/2014:17:08:17 +0200] cupsdRegisterPrinter(p=0x7f4fa6a73450(HP_LaserJet_P1005))
>D [11/Sep/2014:17:08:17 +0200] cupsdMarkDirty(--p--)
>D [11/Sep/2014:17:08:17 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
>I [11/Sep/2014:17:08:17 +0200] Partial reload complete.
>D [11/Sep/2014:17:08:17 +0200] systemd_checkin: Matched existing listener /var/run/cups/cups.sock with fd 3...
>D [11/Sep/2014:17:08:17 +0200] Calling FindDeviceById(cups-Cups-PDF)
>D [11/Sep/2014:17:08:17 +0200] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_Cups_PDF)
>D [11/Sep/2014:17:08:17 +0200] Using profile ID "Cups-PDF-Gray..".
>D [11/Sep/2014:17:08:17 +0200] Calling CreateProfile(Cups-PDF-Gray..,temp)
>W [11/Sep/2014:17:08:17 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-Gray..' already exists
>D [11/Sep/2014:17:08:17 +0200] Using profile ID "Cups-PDF-RGB..".
>D [11/Sep/2014:17:08:17 +0200] Calling CreateProfile(Cups-PDF-RGB..,temp)
>W [11/Sep/2014:17:08:17 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-RGB..' already exists
>I [11/Sep/2014:17:08:17 +0200] Registering ICC color profiles for "Cups-PDF".
>D [11/Sep/2014:17:08:17 +0200] Calling CreateDevice(cups-Cups-PDF,temp)
>D [11/Sep/2014:17:08:18 +0200] Created device "/org/freedesktop/ColorManager/devices/cups_Cups_PDF".
>D [11/Sep/2014:17:08:18 +0200] Calling FindDeviceById(cups-HP_LaserJet_P1005)
>D [11/Sep/2014:17:08:18 +0200] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005)
>D [11/Sep/2014:17:08:18 +0200] Using profile ID "HP_LaserJet_P1005-Gray..".
>D [11/Sep/2014:17:08:18 +0200] Calling CreateProfile(HP_LaserJet_P1005-Gray..,temp)
>W [11/Sep/2014:17:08:18 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>D [11/Sep/2014:17:08:18 +0200] Using profile ID "HP_LaserJet_P1005-RGB..".
>D [11/Sep/2014:17:08:18 +0200] Calling CreateProfile(HP_LaserJet_P1005-RGB..,temp)
>W [11/Sep/2014:17:08:18 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>I [11/Sep/2014:17:08:18 +0200] Registering ICC color profiles for "HP_LaserJet_P1005".
>D [11/Sep/2014:17:08:18 +0200] Calling CreateDevice(cups-HP_LaserJet_P1005,temp)
>D [11/Sep/2014:17:08:18 +0200] Created device "/org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005".
>I [11/Sep/2014:17:08:18 +0200] Listening to [v1.::1]:631 on fd 10...
>I [11/Sep/2014:17:08:18 +0200] Listening to 127.0.0.1:631 on fd 11...
>I [11/Sep/2014:17:08:18 +0200] Listening to /var/run/cups/cups.sock:631 on fd 3...
>I [11/Sep/2014:17:08:18 +0200] Resuming new connection processing...
>D [11/Sep/2014:17:08:18 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:17:08:18 +0200] Discarding unused server-restarted event...
>D [11/Sep/2014:17:08:18 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:18 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:18 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:17:08:18 +0200] Report: clients=1
>D [11/Sep/2014:17:08:18 +0200] Report: jobs=40
>D [11/Sep/2014:17:08:18 +0200] Report: jobs-active=1
>D [11/Sep/2014:17:08:18 +0200] Report: printers=2
>D [11/Sep/2014:17:08:18 +0200] Report: stringpool-string-count=6105
>D [11/Sep/2014:17:08:18 +0200] Report: stringpool-alloc-bytes=13408
>D [11/Sep/2014:17:08:18 +0200] Report: stringpool-total-bytes=114344
>D [11/Sep/2014:17:08:18 +0200] PID 21959 (/usr/lib/cups/notifier/dbus) exited with no errors.
>D [11/Sep/2014:17:08:18 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:18 +0200] [Client 15] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:18 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:08:30 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:30 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:30 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:08:30 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:17:08:30 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:08:30 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:17:08:30 +0200] [Client 16] 1.1 Get-Notifications 17
>D [11/Sep/2014:17:08:30 +0200] Get-Notifications /
>D [11/Sep/2014:17:08:30 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:08:30 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:08:30 +0200] [Client 16] Content-Length: 127
>D [11/Sep/2014:17:08:30 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6b084f0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:08:30 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:08:30 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:08:30 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:08:30 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:08:30 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:08:30 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:17:08:30 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:17:08:48 +0200] [Client 14] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:08:48 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:17:08:48 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>I [11/Sep/2014:17:08:48 +0200] Generating printcap /etc/printcap...
>D [11/Sep/2014:17:08:48 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>D [11/Sep/2014:17:08:49 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] [Client 14] GET /admin/log/error_log HTTP/1.1
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] [Client 14] No authentication data provided.
>I [11/Sep/2014:17:08:49 +0200] [Client 14] Files/directories such as "/var/log/cups/error_log" must be world-readable.
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Closing because Keep-Alive disabled
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:17:08:49 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] [Client 14] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] [Client 14] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] [Client 14] GET /admin/log/error_log HTTP/1.1
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] [Client 14] No authentication data provided.
>I [11/Sep/2014:17:08:49 +0200] [Client 14] Files/directories such as "/var/log/cups/error_log" must be world-readable.
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Closing because Keep-Alive disabled
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] GET /admin/log/error_log HTTP/1.1
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] No authentication data provided.
>I [11/Sep/2014:17:08:49 +0200] [Client 15] Files/directories such as "/var/log/cups/error_log" must be world-readable.
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Closing because Keep-Alive disabled
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Closing connection.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:17:08:49 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:49 +0200] [Client 14] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Accepted from localhost (Domain)
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:08:49 +0200] [Client 15] 1.1 Get-Jobs 18
>D [11/Sep/2014:17:08:49 +0200] Get-Jobs ipp://localhost/printers/
>D [11/Sep/2014:17:08:49 +0200] [Job 105] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Content-Length: 166
>D [11/Sep/2014:17:08:49 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=166, response=0x7f4fa6a87980(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:08:49 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:08:49 +0200] [Client 15] 1.1 Get-Jobs 19
>D [11/Sep/2014:17:08:49 +0200] Get-Jobs ipp://localhost/printers/
>D [11/Sep/2014:17:08:49 +0200] [Job 25] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 26] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 27] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 28] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 29] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 30] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 31] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 32] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 33] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 34] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 35] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 37] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 42] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 44] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 94] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 95] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 96] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 97] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 98] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 99] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 100] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 101] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 102] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 103] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 104] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 106] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 117] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 118] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 124] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 127] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 144] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 145] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 146] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 147] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 148] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 149] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 150] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 151] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Job 152] Loading attributes...
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Content-Length: 3727
>D [11/Sep/2014:17:08:49 +0200] [Job 25] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 26] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 27] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 28] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 29] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 30] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 31] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 32] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 33] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 34] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 35] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 37] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 42] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 44] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 94] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 95] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 96] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 97] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 98] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 99] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 100] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 101] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 102] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 103] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 104] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 106] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 117] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 118] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 124] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Job 127] Removing document files.
>D [11/Sep/2014:17:08:49 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3727, response=0x7f4fa6ab0cc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:08:49 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:08:49 +0200] [Client 15] 1.1 Create-Printer-Subscriptions 20
>D [11/Sep/2014:17:08:49 +0200] Create-Printer-Subscriptions /
>D [11/Sep/2014:17:08:49 +0200] create_subscriptions(con=0x7f4fa6abbb40(15), uri="/")
>D [11/Sep/2014:17:08:49 +0200] pullmethod="ippget"
>D [11/Sep/2014:17:08:49 +0200] notify-lease-duration=86400
>D [11/Sep/2014:17:08:49 +0200] notify-time-interval=0
>D [11/Sep/2014:17:08:49 +0200] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")
>D [11/Sep/2014:17:08:49 +0200] Added subscription #184 for server.
>D [11/Sep/2014:17:08:49 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Content-Length: 107
>D [11/Sep/2014:17:08:49 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x7f4fa6a87980(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:08:49 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:08:49 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:08:49 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:08:50 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:08:50 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:08:50 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:08:50 +0200] [Client 15] 1.1 Get-Notifications 21
>D [11/Sep/2014:17:08:50 +0200] Get-Notifications /
>D [11/Sep/2014:17:08:50 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:08:50 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:08:50 +0200] [Client 15] Content-Length: 127
>D [11/Sep/2014:17:08:50 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6ab0cc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:08:50 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:08:50 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:08:50 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:08:50 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:07 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:07 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] [Client 16] POST /printers/HP%5FLaserJet%5FP1005 HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 16] 1.1 Print-Job 22
>D [11/Sep/2014:17:09:07 +0200] Print-Job ipp://localhost/printers/HP%5FLaserJet%5FP1005
>D [11/Sep/2014:17:09:07 +0200] [Job ???] Auto-typing file...
>I [11/Sep/2014:17:09:07 +0200] [Job ???] Request file type is application/vnd.cups-pdf-banner.
>D [11/Sep/2014:17:09:07 +0200] add_job: setting context of job to UNKNOWN SL
>D [11/Sep/2014:17:09:07 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:07 +0200] add_job: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:07 +0200] Adding default job-sheets values "none,none"...
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Adding start banner page "none".
>D [11/Sep/2014:17:09:07 +0200] Notifier dbus started - PID = 29004
>D [11/Sep/2014:17:09:07 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Adding end banner page "none".
>I [11/Sep/2014:17:09:07 +0200] [Job 153] File of type application/vnd.cups-pdf-banner queued by "pdupre".
>D [11/Sep/2014:17:09:07 +0200] [Job 153] hold_until=0
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Queued on "HP_LaserJet_P1005" by "pdupre".
>D [11/Sep/2014:17:09:07 +0200] [Job 153] time-at-processing=1410448147
>D [11/Sep/2014:17:09:07 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] 4 filters for job:
>D [11/Sep/2014:17:09:07 +0200] [Job 153] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32)
>D [11/Sep/2014:17:09:07 +0200] [Job 153] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
>D [11/Sep/2014:17:09:07 +0200] [Job 153] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
>D [11/Sep/2014:17:09:07 +0200] [Job 153] hpcups (application/vnd.cups-raster to printer/HP_LaserJet_P1005, cost 0)
>D [11/Sep/2014:17:09:07 +0200] [Job 153] job-sheets=none,none
>D [11/Sep/2014:17:09:07 +0200] [Job 153] argv[0]="HP_LaserJet_P1005"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] argv[1]="153"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] argv[2]="pdupre"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] argv[3]="Test Page"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] argv[4]="1"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] argv[5]="job-uuid=urn:uuid:3271c25c-d211-3ac5-75d9-f2dc90f4b247 job-originating-host-name=localhost time-at-creation=1410448147 time-at-processing=1410448147"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] argv[6]="/var/spool/cups/d00153-001"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[8]="HOME=/var/spool/cups/tmp"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[10]="SERVER_ADMIN=root@teucidide"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[11]="SOFTWARE=CUPS/1.7.4"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[12]="TMPDIR=/var/spool/cups/tmp"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[13]="USER=root"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[14]="CUPS_MAX_MESSAGE=2047"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[16]="CUPS_ENCRYPTION=IfRequested"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[17]="IPP_PORT=631"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[18]="CHARSET=utf-8"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[19]="LANG=en_US.UTF-8"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_P1005.ppd"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[21]="RIP_MAX_CACHE=128m"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_P1005?serial=BB07BPM"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[24]="PRINTER_INFO=HP_LaserJet_P1005"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[25]="PRINTER_LOCATION="
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[26]="PRINTER=HP_LaserJet_P1005"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[27]="PRINTER_STATE_REASONS=none"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[28]="CUPS_FILETYPE=document"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[29]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_P1005"
>D [11/Sep/2014:17:09:07 +0200] [Job 153] envp[30]="AUTH_I****"
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Started filter /usr/lib/cups/filter/bannertopdf (PID 29005)
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Started filter /usr/lib/cups/filter/pdftopdf (PID 29006)
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Started filter /usr/lib/cups/filter/gstoraster (PID 29007)
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Started filter /usr/lib/cups/filter/hpcups (PID 29008)
>I [11/Sep/2014:17:09:07 +0200] [Job 153] Started backend /usr/lib/cups/backend/hp (PID 29009)
>D [11/Sep/2014:17:09:07 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 16] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/HP%5FLaserJet%5FP1005) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 16] Content-Length: 175
>D [11/Sep/2014:17:09:07 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=175, response=0x7f4fa6a87a80(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:07 +0200] [Notifier] Connected to D-BUS
>D [11/Sep/2014:17:09:07 +0200] [Notifier] JobCreated
>D [11/Sep/2014:17:09:07 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:07 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:07 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:07 +0200] [Notifier] JobState
>D [11/Sep/2014:17:09:07 +0200] [Job 153] STATE: -marker-supply-low-warning
>D [11/Sep/2014:17:09:07 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:07 +0200] [Client 22] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:07 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 22] 1.1 Get-Notifications 163
>D [11/Sep/2014:17:09:07 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:07 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:07 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 22] Content-Length: 1623
>D [11/Sep/2014:17:09:07 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1623, response=0x7f4fa6b0c990(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:07 +0200] [Client 23] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:07 +0200] [Client 23] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] [Client 23] POST / HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 23] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 23] 1.1 Get-Job-Attributes 164
>D [11/Sep/2014:17:09:07 +0200] Get-Job-Attributes ipp://localhost/jobs/153
>D [11/Sep/2014:17:09:07 +0200] [Client 23] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/153) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 23] Content-Length: 112
>D [11/Sep/2014:17:09:07 +0200] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=112, response=0x7f4fa6b154f0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 23] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 23] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:07 +0200] [Client 24] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:07 +0200] [Client 24] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] [Client 24] POST / HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 24] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 24] 1.1 Get-Job-Attributes 165
>D [11/Sep/2014:17:09:07 +0200] Get-Job-Attributes ipp://localhost/jobs/153
>D [11/Sep/2014:17:09:07 +0200] [Client 24] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/153) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 24] Content-Length: 112
>D [11/Sep/2014:17:09:07 +0200] [Client 24] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=112, response=0x7f4fa6b27a10(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 24] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 24] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 24] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 25] 1.1 Get-Notifications 23
>D [11/Sep/2014:17:09:07 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:07 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Content-Length: 574
>D [11/Sep/2014:17:09:07 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=574, response=0x7f4fa6aa7ef0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 25] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Closing connection.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 15] 1.1 Get-Notifications 24
>D [11/Sep/2014:17:09:07 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:07 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:07 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 15] Content-Length: 1176
>D [11/Sep/2014:17:09:07 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1176, response=0x7f4fa6b15500(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:07 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 25] 1.1 CUPS-Get-Printers 25
>D [11/Sep/2014:17:09:07 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Content-Length: 835
>D [11/Sep/2014:17:09:07 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=835, response=0x7f4fa6aa75c0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:07 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:07 +0200] [Client 25] 1.1 CUPS-Get-Classes 26
>D [11/Sep/2014:17:09:07 +0200] CUPS-Get-Classes
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:17:09:07 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6a87a80(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:07 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:07 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:07 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] PDF template file doesn't have form. It's okay.
>D [11/Sep/2014:17:09:08 +0200] [Job 153] PID 29005 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
>D [11/Sep/2014:17:09:08 +0200] [Job 153] PID 29006 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Calling FindDeviceById(cups-HP_LaserJet_P1005)
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Found device /org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
>D [11/Sep/2014:17:09:08 +0200] [Job 153] PPD uses qualifier 'Gray.Plain.'
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Calling FindDeviceById(cups-HP_LaserJet_P1005)
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Found device /org/freedesktop/ColorManager/devices/cups_HP_LaserJet_P1005
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Calling GetProfileForQualifiers(Gray.Plain....)
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Failed to send: org.freedesktop.ColorManager.Device.NothingMatched:nothing matched expression 'Gray.Plain.,Gray.Plain.*,Gray.*.,Gray.*.*,*'
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Failed to get profile filename for cups-HP_LaserJet_P1005
>I [11/Sep/2014:17:09:08 +0200] [Job 153] no profiles specified in PPD
>D [11/Sep/2014:17:09:08 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
>D [11/Sep/2014:17:09:08 +0200] [Job 153] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -sMediaType=Plain -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=1 -dcupsColorOrder=0 -dcupsColorSpace=3 -dcupsRowCount=1 -dcupsRowStep=2 -dcupsInteger0=9 -dcupsInteger1=600 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c '<</.HWMargins[13.560000 13.500000 13.559998 13.500000] /Margins[0 0]>>setpagedevice' -f -_
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[8]="HOME=/var/spool/cups/tmp"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[10]="SERVER_ADMIN=root@teucidide"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[11]="SOFTWARE=CUPS/1.7.4"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[12]="TMPDIR=/var/spool/cups/tmp"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[13]="USER=root"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[14]="CUPS_MAX_MESSAGE=2047"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[16]="CUPS_ENCRYPTION=IfRequested"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[17]="IPP_PORT=631"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[18]="CHARSET=utf-8"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[19]="LANG=en_US.UTF-8"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_P1005.ppd"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[21]="RIP_MAX_CACHE=128m"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_P1005?serial=BB07BPM"
>D [11/Sep/2014:17:09:08 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[24]="PRINTER_INFO=HP_LaserJet_P1005"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[25]="PRINTER_LOCATION="
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[26]="PRINTER=HP_LaserJet_P1005"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[27]="PRINTER_STATE_REASONS=none"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[28]="CUPS_FILETYPE=document"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[29]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_P1005"
>D [11/Sep/2014:17:09:08 +0200] [Job 153] envp[30]="AUTH_INFO_REQUIRED=none"
>D [11/Sep/2014:17:09:08 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:08 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:08 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 22] 1.1 Get-Notifications 166
>D [11/Sep/2014:17:09:08 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:08 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:17:09:08 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6aab750(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 22] 1.1 CUPS-Get-Printers 167
>D [11/Sep/2014:17:09:08 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:17:09:08 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6aa6cc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 22] 1.1 Get-Notifications 168
>D [11/Sep/2014:17:09:08 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:08 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Content-Length: 127
>D [11/Sep/2014:17:09:08 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6aab750(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 22] 1.1 Get-Notifications 169
>D [11/Sep/2014:17:09:08 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:08 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Content-Length: 127
>D [11/Sep/2014:17:09:08 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6aa6cc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:08 +0200] [Client 26] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:08 +0200] [Client 26] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] [Client 26] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 26] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 26] 1.1 Get-Notifications 27
>D [11/Sep/2014:17:09:08 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:08 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:08 +0200] [Client 26] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 26] Content-Length: 560
>D [11/Sep/2014:17:09:08 +0200] [Client 26] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6aab740(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 26] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 26] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 26] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 26] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:08 +0200] [Client 26] Closing connection.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 15] 1.1 Get-Notifications 28
>D [11/Sep/2014:17:09:08 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:08 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:08 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 15] Content-Length: 667
>D [11/Sep/2014:17:09:08 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=667, response=0x7f4fa6aab710(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 25] 1.1 CUPS-Get-Printers 29
>D [11/Sep/2014:17:09:08 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Content-Length: 863
>D [11/Sep/2014:17:09:08 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=863, response=0x7f4fa6aa6cc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:08 +0200] [Client 25] 1.1 CUPS-Get-Classes 30
>D [11/Sep/2014:17:09:08 +0200] CUPS-Get-Classes
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:17:09:08 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6aab710(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:08 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:08 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:08 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:08 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:17:09:08 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>I [11/Sep/2014:17:09:09 +0200] [Job 153] Start rendering...
>D [11/Sep/2014:17:09:09 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Job 153] Set job-printer-state-message to "Start rendering...", current level=INFO
>D [11/Sep/2014:17:09:09 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:09 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:09 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:09 +0200] [Client 22] 1.1 Get-Notifications 170
>D [11/Sep/2014:17:09:09 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:09 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:17:09:09 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6b3bd20(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:09 +0200] [Client 22] 1.1 CUPS-Get-Printers 171
>D [11/Sep/2014:17:09:09 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:17:09:09 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b3bae0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>I [11/Sep/2014:17:09:09 +0200] [Job 153] Processing page 1...
>D [11/Sep/2014:17:09:09 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Job 153] Set job-printer-state-message to "Processing page 1...", current level=INFO
>D [11/Sep/2014:17:09:09 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:09 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:09 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:09 +0200] [Client 22] 1.1 Get-Notifications 172
>D [11/Sep/2014:17:09:09 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:09 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:17:09:09 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6b3cd20(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:09 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:09 +0200] [Client 22] 1.1 CUPS-Get-Printers 173
>D [11/Sep/2014:17:09:09 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:17:09:09 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6b3c740(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:09 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:10 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:10 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:10 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:10 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:17:09:10 +0200] [Client 16] 1.1 Get-Notifications 31
>D [11/Sep/2014:17:09:10 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:10 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:10 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:10 +0200] [Client 16] Content-Length: 993
>D [11/Sep/2014:17:09:10 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=993, response=0x7f4fa6b3c0e0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:10 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:10 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:10 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:10 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:10 +0200] [Client 15] 1.1 Get-Notifications 32
>D [11/Sep/2014:17:09:10 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:10 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:10 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:10 +0200] [Client 15] Content-Length: 1189
>D [11/Sep/2014:17:09:10 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1189, response=0x7f4fa6b3d190(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:10 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:10 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:10 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:10 +0200] [Client 25] 1.1 CUPS-Get-Printers 33
>D [11/Sep/2014:17:09:10 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Content-Length: 855
>D [11/Sep/2014:17:09:10 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=855, response=0x7f4fa6b3c0e0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:10 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:10 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:10 +0200] [Client 25] 1.1 CUPS-Get-Classes 34
>D [11/Sep/2014:17:09:10 +0200] CUPS-Get-Classes
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:17:09:10 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b3d190(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:10 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:10 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:10 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Job 153] STATE: +connecting-to-device
>D [11/Sep/2014:17:09:14 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:14 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:14 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 22] 1.1 Get-Notifications 174
>D [11/Sep/2014:17:09:14 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:14 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Content-Length: 576
>D [11/Sep/2014:17:09:14 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=576, response=0x7f4fa6b3d4b0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 22] 1.1 CUPS-Get-Printers 175
>D [11/Sep/2014:17:09:14 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Content-Length: 2108
>D [11/Sep/2014:17:09:14 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2108, response=0x7f4fa6b3d460(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Job 153] STATE: -connecting-to-device
>D [11/Sep/2014:17:09:14 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:14 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:14 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:14 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 22] 1.1 Get-Notifications 176
>D [11/Sep/2014:17:09:14 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:14 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:17:09:14 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6b3dcf0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 22] 1.1 CUPS-Get-Printers 177
>D [11/Sep/2014:17:09:14 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:17:09:14 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6aabdf0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Job 153] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
>D [11/Sep/2014:17:09:14 +0200] [Job 153] PAGE: 1 1
>D [11/Sep/2014:17:09:14 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:14 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:14 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 16] 1.1 Get-Notifications 35
>D [11/Sep/2014:17:09:14 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:14 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:14 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 16] Content-Length: 1009
>D [11/Sep/2014:17:09:14 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1009, response=0x7f4fa6b3c550(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:14 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:14 +0200] [Client 26] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:14 +0200] [Client 26] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:14 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 15] 1.1 Get-Notifications 36
>D [11/Sep/2014:17:09:14 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:14 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:14 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 15] Content-Length: 657
>D [11/Sep/2014:17:09:14 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=657, response=0x7f4fa6b3c550(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 25] 1.1 CUPS-Get-Printers 37
>D [11/Sep/2014:17:09:14 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Content-Length: 855
>D [11/Sep/2014:17:09:14 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=855, response=0x7f4fa6b3c8a0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:14 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:14 +0200] [Client 25] 1.1 CUPS-Get-Classes 38
>D [11/Sep/2014:17:09:14 +0200] CUPS-Get-Classes
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:17:09:14 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b3c550(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:14 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:14 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:14 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>I [11/Sep/2014:17:09:18 +0200] [Job 153] Processing page 2...
>D [11/Sep/2014:17:09:18 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:18 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:18 +0200] [Job 153] Set job-printer-state-message to "Processing page 2...", current level=INFO
>D [11/Sep/2014:17:09:18 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:18 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:18 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:18 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:18 +0200] Report: clients=7
>D [11/Sep/2014:17:09:18 +0200] Report: jobs=41
>D [11/Sep/2014:17:09:18 +0200] Report: jobs-active=2
>D [11/Sep/2014:17:09:18 +0200] Report: printers=2
>D [11/Sep/2014:17:09:18 +0200] Report: stringpool-string-count=8397
>D [11/Sep/2014:17:09:18 +0200] Report: stringpool-alloc-bytes=17888
>D [11/Sep/2014:17:09:18 +0200] Report: stringpool-total-bytes=160440
>D [11/Sep/2014:17:09:18 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:18 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:18 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:18 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:18 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:18 +0200] [Client 22] 1.1 Get-Notifications 178
>D [11/Sep/2014:17:09:18 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:18 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:17:09:18 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6aac960(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:18 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:18 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:18 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:18 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:18 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:18 +0200] [Client 22] 1.1 CUPS-Get-Printers 179
>D [11/Sep/2014:17:09:18 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:17:09:18 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6aac720(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:18 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:18 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:18 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>I [11/Sep/2014:17:09:19 +0200] [Job 153] Rendering completed
>D [11/Sep/2014:17:09:19 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:19 +0200] [Job 153] Set job-printer-state-message to "Rendering completed", current level=INFO
>D [11/Sep/2014:17:09:19 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:19 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>I [11/Sep/2014:17:09:19 +0200] Saving job.cache...
>I [11/Sep/2014:17:09:19 +0200] Saving subscriptions.conf...
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:19 +0200] [Job 153] PID 29007 (/usr/lib/cups/filter/gstoraster) exited with no errors.
>D [11/Sep/2014:17:09:19 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:19 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:19 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:19 +0200] [Client 22] 1.1 Get-Notifications 180
>D [11/Sep/2014:17:09:19 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:19 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:17:09:19 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:19 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:19 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:19 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6aacd20(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:19 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:17:09:19 +0200] [Client 16] 1.1 Get-Notifications 39
>D [11/Sep/2014:17:09:19 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:19 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:19 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:19 +0200] [Client 16] Content-Length: 993
>D [11/Sep/2014:17:09:19 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=993, response=0x7f4fa6aad2d0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:19 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:19 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:19 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:19 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:19 +0200] [Client 15] 1.1 Get-Notifications 40
>D [11/Sep/2014:17:09:19 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:19 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:19 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:19 +0200] [Client 15] Content-Length: 1190
>D [11/Sep/2014:17:09:19 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1190, response=0x7f4fa6aad4e0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:19 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:19 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:19 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:19 +0200] [Client 22] 1.1 CUPS-Get-Printers 181
>D [11/Sep/2014:17:09:19 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:17:09:19 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6aad2d0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:19 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:19 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:19 +0200] [Client 25] 1.1 CUPS-Get-Printers 41
>D [11/Sep/2014:17:09:19 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Content-Length: 854
>D [11/Sep/2014:17:09:19 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=854, response=0x7f4fa6aae1f0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:19 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:19 +0200] [Client 25] 1.1 CUPS-Get-Classes 42
>D [11/Sep/2014:17:09:19 +0200] CUPS-Get-Classes
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:17:09:19 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6aad2d0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:19 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:19 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:19 +0200] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
>D [11/Sep/2014:17:09:19 +0200] [Job 153] PID 29008 (/usr/lib/cups/filter/hpcups) exited with no errors.
>I [11/Sep/2014:17:09:39 +0200] [Job 153] ready to print
>D [11/Sep/2014:17:09:39 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
>D [11/Sep/2014:17:09:39 +0200] [Job 153] Set job-printer-state-message to "ready to print", current level=INFO
>D [11/Sep/2014:17:09:39 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:39 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:39 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:39 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:39 +0200] [Client 22] 1.1 Get-Notifications 182
>D [11/Sep/2014:17:09:39 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:39 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Content-Length: 560
>D [11/Sep/2014:17:09:39 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6aae0c0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:39 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:39 +0200] [Client 22] 1.1 CUPS-Get-Printers 183
>D [11/Sep/2014:17:09:39 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Content-Length: 2092
>D [11/Sep/2014:17:09:39 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2092, response=0x7f4fa6aacd20(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:39 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:39 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:39 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:39 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:39 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:39 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:17:09:39 +0200] [Client 16] 1.1 Get-Notifications 43
>D [11/Sep/2014:17:09:39 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:39 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:39 +0200] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:39 +0200] [Client 16] Content-Length: 560
>D [11/Sep/2014:17:09:39 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=560, response=0x7f4fa6aadfd0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:39 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:39 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:39 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:39 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:39 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:39 +0200] [Client 15] 1.1 Get-Notifications 44
>D [11/Sep/2014:17:09:39 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:39 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:39 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:39 +0200] [Client 15] Content-Length: 653
>D [11/Sep/2014:17:09:39 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=653, response=0x7f4fa6aae1a0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:39 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:39 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:39 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:39 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Job 153] PID 29009 (/usr/lib/cups/backend/hp) exited with no errors.
>D [11/Sep/2014:17:09:40 +0200] [Job 153] time-at-completed=1410448180
>D [11/Sep/2014:17:09:40 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>I [11/Sep/2014:17:09:40 +0200] [Job 153] Job completed.
>D [11/Sep/2014:17:09:40 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:40 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:40 +0200] cupsdMarkDirty(---J-)
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:40 +0200] [Notifier] JobCompleted
>D [11/Sep/2014:17:09:40 +0200] [Notifier] state=3
>D [11/Sep/2014:17:09:40 +0200] [Notifier] PrinterStateChanged
>D [11/Sep/2014:17:09:40 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 22] 1.1 Get-Notifications 184
>D [11/Sep/2014:17:09:40 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:40 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Content-Length: 1109
>D [11/Sep/2014:17:09:40 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1109, response=0x7f4fa6b36dd0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:40 +0200] [Client 16] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:40 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] [Client 16] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 16] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 16] 1.1 Get-Job-Attributes 185
>D [11/Sep/2014:17:09:40 +0200] Get-Job-Attributes ipp://localhost/jobs/153
>D [11/Sep/2014:17:09:40 +0200] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/153) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 16] Content-Length: 112
>D [11/Sep/2014:17:09:40 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=112, response=0x7f4fa6b36d50(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 16] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 22] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 22] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 22] 1.1 Get-Notifications 186
>D [11/Sep/2014:17:09:40 +0200] Get-Notifications /printers/
>D [11/Sep/2014:17:09:40 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Content-Length: 127
>D [11/Sep/2014:17:09:40 +0200] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f4fa6b36bc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 22] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 22] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 25] 1.1 CUPS-Get-Printers 45
>D [11/Sep/2014:17:09:40 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Content-Length: 849
>D [11/Sep/2014:17:09:40 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=849, response=0x7f4fa6b36d50(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 25] 1.1 CUPS-Get-Classes 46
>D [11/Sep/2014:17:09:40 +0200] CUPS-Get-Classes
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:17:09:40 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b36bc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:40 +0200] [Client 20] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:40 +0200] [Client 20] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] [Client 20] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 20] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 20] 1.1 Get-Notifications 47
>D [11/Sep/2014:17:09:40 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:40 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:40 +0200] [Client 20] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 20] Content-Length: 568
>D [11/Sep/2014:17:09:40 +0200] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=568, response=0x7f4fa6b36d90(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 20] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 20] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 20] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:09:40 +0200] [Client 20] Closing connection.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 15] 1.1 Get-Notifications 48
>D [11/Sep/2014:17:09:40 +0200] Get-Notifications /
>D [11/Sep/2014:17:09:40 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:40 +0200] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 15] Content-Length: 668
>D [11/Sep/2014:17:09:40 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=668, response=0x7f4fa6b36bc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 25] 1.1 CUPS-Get-Printers 49
>D [11/Sep/2014:17:09:40 +0200] CUPS-Get-Printers
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Content-Length: 849
>D [11/Sep/2014:17:09:40 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=849, response=0x7f4fa6b36d90(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] POST / HTTP/1.1
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:40 +0200] [Client 25] No authentication data provided.
>D [11/Sep/2014:17:09:40 +0200] [Client 25] 1.1 CUPS-Get-Classes 50
>D [11/Sep/2014:17:09:40 +0200] CUPS-Get-Classes
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Content-Length: 75
>D [11/Sep/2014:17:09:40 +0200] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b36bc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:40 +0200] [Client 25] Waiting for request.
>D [11/Sep/2014:17:09:40 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:50 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:50 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:50 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:50 +0200] [Client 15] 1.1 Get-Job-Attributes 51
>D [11/Sep/2014:17:09:50 +0200] Get-Job-Attributes ipp://localhost/jobs/153
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/153) from localhost
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Content-Length: 854
>D [11/Sep/2014:17:09:50 +0200] [Job 25] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 26] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 27] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 28] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 29] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 30] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 31] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 32] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 33] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 34] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 35] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 37] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 42] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 44] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 94] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 95] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 96] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 97] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 98] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 99] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 100] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 101] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 102] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 103] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 104] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 105] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 106] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 117] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 118] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 124] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 127] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 144] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 145] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 146] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 147] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 148] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 149] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 150] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 151] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Job 152] Unloading...
>D [11/Sep/2014:17:09:50 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=854, response=0x7f4fa6b36d90(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:50 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:50 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:50 +0200] [Client 15] POST / HTTP/1.1
>D [11/Sep/2014:17:09:50 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>D [11/Sep/2014:17:09:50 +0200] [Client 15] No authentication data provided.
>D [11/Sep/2014:17:09:50 +0200] [Client 15] 1.1 Cancel-Subscription 52
>D [11/Sep/2014:17:09:50 +0200] Cancel-Subscription /
>D [11/Sep/2014:17:09:50 +0200] cupsdIsAuthorized: requesting-user-name="pdupre"
>D [11/Sep/2014:17:09:50 +0200] cupsdMarkDirty(----S)
>D [11/Sep/2014:17:09:50 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Content-Length: 75
>D [11/Sep/2014:17:09:50 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f4fa6b36bc0(IPP_IDLE), pipe_pid=0, file=-1
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
>D [11/Sep/2014:17:09:50 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
>D [11/Sep/2014:17:09:50 +0200] [Client 15] Waiting for request.
>D [11/Sep/2014:17:09:50 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>D [11/Sep/2014:17:09:50 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:09:50 +0200] [Client 20] Accepted from localhost (Domain)
>D [11/Sep/2014:17:09:50 +0200] [Client 20] Waiting for request.
>I [11/Sep/2014:17:10:11 +0200] Saving job.cache...
>I [11/Sep/2014:17:10:11 +0200] Saving subscriptions.conf...
>D [11/Sep/2014:17:10:11 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>D [11/Sep/2014:17:10:41 +0200] [Client 20] PUT /admin/conf/cupsd.conf HTTP/1.1
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 20] No authentication data provided.
>D [11/Sep/2014:17:10:41 +0200] cupsdIsAuthorized: username=""
>D [11/Sep/2014:17:10:41 +0200] [Client 20] WWW-Authenticate: Basic realm="CUPS", trc="y"
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:17:10:41 +0200] Report: clients=8
>D [11/Sep/2014:17:10:41 +0200] Report: jobs=41
>D [11/Sep/2014:17:10:41 +0200] Report: jobs-active=1
>D [11/Sep/2014:17:10:41 +0200] Report: printers=2
>D [11/Sep/2014:17:10:41 +0200] Report: stringpool-string-count=6626
>D [11/Sep/2014:17:10:41 +0200] Report: stringpool-alloc-bytes=14112
>D [11/Sep/2014:17:10:41 +0200] Report: stringpool-total-bytes=125688
>D [11/Sep/2014:17:10:41 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Accepted from localhost (Domain)
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Waiting for request.
>D [11/Sep/2014:17:10:41 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:10:41 +0200] [Client 27] Accepted from localhost (Domain)
>D [11/Sep/2014:17:10:41 +0200] [Client 27] Waiting for request.
>D [11/Sep/2014:17:10:41 +0200] [Client 20] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] cupsdAcceptClient: skipping getpeercon()
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Accepted from localhost (Domain)
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Waiting for request.
>D [11/Sep/2014:17:10:41 +0200] [Client 27] HTTP_STATE_WAITING Closing on EOF
>D [11/Sep/2014:17:10:41 +0200] [Client 27] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 20] PUT /admin/conf/cupsd.conf HTTP/1.1
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Authorized as root using PeerCred
>D [11/Sep/2014:17:10:41 +0200] cupsdIsAuthorized: username="root"
>I [11/Sep/2014:17:10:41 +0200] Installing config file "/etc/cups/cupsd.conf"...
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>D [11/Sep/2014:17:10:41 +0200] [Client 14] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 15] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 22] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 23] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 24] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 25] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 26] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 16] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>D [11/Sep/2014:17:10:41 +0200] [Client 20] Closing connection.
>D [11/Sep/2014:17:10:41 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>E [11/Sep/2014:17:10:41 +0200] Unknown directive JobPrivateAccess on line 84 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:10:41 +0200] Unknown directive JobPrivateValues on line 85 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:10:41 +0200] Unknown directive SubscriptionPrivateAccess on line 86 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:10:41 +0200] Unknown directive SubscriptionPrivateValues on line 87 of /etc/cups/cupsd.conf.
>W [11/Sep/2014:17:10:41 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-Gray..' already exists
>W [11/Sep/2014:17:10:41 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-RGB..' already exists
>W [11/Sep/2014:17:10:41 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>W [11/Sep/2014:17:10:41 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists
>E [11/Sep/2014:17:12:47 +0200] Unknown directive JobPrivateAccess on line 83 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:12:47 +0200] Unknown directive JobPrivateValues on line 84 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:12:47 +0200] Unknown directive SubscriptionPrivateAccess on line 85 of /etc/cups/cupsd.conf.
>E [11/Sep/2014:17:12:47 +0200] Unknown directive SubscriptionPrivateValues on line 86 of /etc/cups/cupsd.conf.
>W [11/Sep/2014:17:12:47 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-Gray..' already exists
>W [11/Sep/2014:17:12:47 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Cups-PDF-RGB..' already exists
>W [11/Sep/2014:17:12:47 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-Gray..' already exists
>W [11/Sep/2014:17:12:47 +0200] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_P1005-RGB..' already exists

Actions: View

Attachments on bug 1140807: 936649