I [19/Oct/2011:07:38:14 +0300] Saving subscriptions.conf...
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [19/Oct/2011:07:38:14 +0300] Listening to [v1.::1]:631 (IPv6)
I [19/Oct/2011:07:38:14 +0300] Listening to 127.0.0.1:631 (IPv4)
I [19/Oct/2011:07:38:14 +0300] Listening to /var/run/cups/cups.sock (Domain)
I [19/Oct/2011:07:38:14 +0300] Remote access is disabled.
D [19/Oct/2011:07:38:14 +0300] Added auto ServerAlias nexus6
I [19/Oct/2011:07:38:14 +0300] Loaded configuration file "/etc/cups/cupsd.conf"
I [19/Oct/2011:07:38:14 +0300] Using default TempDir of /var/spool/cups/tmp...
I [19/Oct/2011:07:38:14 +0300] Configured for up to 100 clients.
I [19/Oct/2011:07:38:14 +0300] Allowing up to 100 client connections per host.
I [19/Oct/2011:07:38:14 +0300] Using policy "default" as the default.
I [19/Oct/2011:07:38:14 +0300] Full reload is required.
I [19/Oct/2011:07:38:14 +0300] Saving job.cache...
D [19/Oct/2011:07:38:14 +0300] Discarding unused printer-stopped event...
D [19/Oct/2011:07:38:14 +0300] cupsdMarkDirty(P-----)
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
D [19/Oct/2011:07:38:14 +0300] Calling DeleteDevice(cups-mankeli)
D [19/Oct/2011:07:38:14 +0300] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [19/Oct/2011:07:38:14 +0300] cupsdDeregisterPrinter(p=0x7fb25543e4c0(mankeli), removeit=1)
I [19/Oct/2011:07:38:14 +0300] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 78 filters...
D [19/Oct/2011:07:38:14 +0300] Loading printer mankeli...
D [19/Oct/2011:07:38:14 +0300] load_ppd: Loading /var/cache/cups/mankeli.data...
D [19/Oct/2011:07:38:14 +0300] Calling DeleteDevice(cups-mankeli)
D [19/Oct/2011:07:38:14 +0300] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [19/Oct/2011:07:38:14 +0300] Using profile id of mankeli-Gray..
D [19/Oct/2011:07:38:14 +0300] Calling CreateProfile(mankeli-Gray..,temp)
D [19/Oct/2011:07:38:14 +0300] created profile /org/freedesktop/ColorManager/profiles/mankeli_Gray__
I [19/Oct/2011:07:38:14 +0300] Registering ICC color profiles for "mankeli"
D [19/Oct/2011:07:38:14 +0300] Calling CreateDevice(cups-mankeli,temp)
D [19/Oct/2011:07:38:14 +0300] created device /org/freedesktop/ColorManager/devices/cups_mankeli
D [19/Oct/2011:07:38:14 +0300] Calling /org/freedesktop/ColorManager/devices/cups_mankeli:AddProfile(/org/freedesktop/ColorManager/profiles/mankeli_Gray__) [soft]
W [19/Oct/2011:07:38:14 +0300] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mankeli_Gray__' has already been added
D [19/Oct/2011:07:38:14 +0300] cupsdRegisterPrinter(p=0x7fb2555cc5e0(mankeli))
D [19/Oct/2011:07:38:14 +0300] load_ppd: Loading /var/cache/cups/mankeli.data...
D [19/Oct/2011:07:38:14 +0300] Calling DeleteDevice(cups-mankeli)
D [19/Oct/2011:07:38:14 +0300] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [19/Oct/2011:07:38:14 +0300] Using profile id of mankeli-Gray..
D [19/Oct/2011:07:38:14 +0300] Calling CreateProfile(mankeli-Gray..,temp)
D [19/Oct/2011:07:38:14 +0300] created profile /org/freedesktop/ColorManager/profiles/mankeli_Gray__
I [19/Oct/2011:07:38:14 +0300] Registering ICC color profiles for "mankeli"
D [19/Oct/2011:07:38:14 +0300] Calling CreateDevice(cups-mankeli,temp)
D [19/Oct/2011:07:38:14 +0300] created device /org/freedesktop/ColorManager/devices/cups_mankeli
D [19/Oct/2011:07:38:14 +0300] Calling /org/freedesktop/ColorManager/devices/cups_mankeli:AddProfile(/org/freedesktop/ColorManager/profiles/mankeli_Gray__) [soft]
W [19/Oct/2011:07:38:14 +0300] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mankeli_Gray__' has already been added
D [19/Oct/2011:07:38:14 +0300] cupsdRegisterPrinter(p=0x7fb2555cc5e0(mankeli))
D [19/Oct/2011:07:38:14 +0300] cupsdLoadRemoteCache: Not loading remote cache.
I [19/Oct/2011:07:38:14 +0300] Loading job cache file "/var/cache/cups/job.cache"...
D [19/Oct/2011:07:38:14 +0300] [Job 1] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 2] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 3] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 4] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 5] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 6] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 7] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 8] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 9] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 10] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 11] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 12] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 13] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 14] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 15] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 16] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 17] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 18] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 19] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 20] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 21] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 22] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 23] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 24] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 25] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 26] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 27] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 28] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 29] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 30] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 31] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 32] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 33] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 34] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 35] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 36] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 37] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 38] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 39] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 40] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 41] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 42] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 43] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 44] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 45] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 46] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 47] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 48] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 49] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] [Job 50] Loading from cache...
D [19/Oct/2011:07:38:14 +0300] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [19/Oct/2011:07:38:14 +0300] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [19/Oct/2011:07:38:14 +0300] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [19/Oct/2011:07:38:14 +0300] Full reload complete.
I [19/Oct/2011:07:38:14 +0300] Listening to [v1.::1]:631 on fd 9...
I [19/Oct/2011:07:38:14 +0300] Listening to 127.0.0.1:631 on fd 10...
I [19/Oct/2011:07:38:14 +0300] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [19/Oct/2011:07:38:14 +0300] Resuming new connection processing...
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Oct/2011:07:38:14 +0300] Discarding unused server-restarted event...
D [19/Oct/2011:07:38:14 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:07:38:14 +0300] cupsdAcceptClient: 254 from localhost (Domain)
D [19/Oct/2011:07:38:14 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [19/Oct/2011:07:38:14 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:07:38:14 +0300] cupsdReadClient: 254 POST / HTTP/1.1
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:07:38:14 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:07:38:14 +0300] cupsdReadClient: 254 1.1 Get-Notifications 1
D [19/Oct/2011:07:38:14 +0300] Get-Notifications /
D [19/Oct/2011:07:38:14 +0300] cupsdIsAuthorized: requesting-user-name="tjaalton"
D [19/Oct/2011:07:38:14 +0300] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [19/Oct/2011:07:38:14 +0300] cupsdReadClient: 253 1.1 Get-Jobs 1
D [19/Oct/2011:07:38:14 +0300] Get-Jobs ipp://localhost/printers/
D [19/Oct/2011:07:38:14 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:07:38:14 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:07:38:14 +0300] cupsdCloseClient: 253
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Oct/2011:07:38:14 +0300] cupsdReadClient: 254 WAITING Closing on EOF
D [19/Oct/2011:07:38:14 +0300] cupsdCloseClient: 254
D [19/Oct/2011:07:38:14 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Oct/2011:07:38:29 +0300] Report: clients=0
D [19/Oct/2011:07:38:29 +0300] Report: jobs=50
D [19/Oct/2011:07:38:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:38:29 +0300] Report: printers=1
D [19/Oct/2011:07:38:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:38:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:38:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:38:29 +0300] Report: stringpool-total-bytes=79768
I [19/Oct/2011:07:38:44 +0300] Saving printers.conf...
D [19/Oct/2011:07:38:44 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Oct/2011:07:39:29 +0300] Report: clients=0
D [19/Oct/2011:07:39:29 +0300] Report: jobs=50
D [19/Oct/2011:07:39:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:39:29 +0300] Report: printers=1
D [19/Oct/2011:07:39:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:39:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:39:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:39:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:40:29 +0300] Report: clients=0
D [19/Oct/2011:07:40:29 +0300] Report: jobs=50
D [19/Oct/2011:07:40:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:40:29 +0300] Report: printers=1
D [19/Oct/2011:07:40:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:40:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:40:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:40:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:41:29 +0300] Report: clients=0
D [19/Oct/2011:07:41:29 +0300] Report: jobs=50
D [19/Oct/2011:07:41:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:41:29 +0300] Report: printers=1
D [19/Oct/2011:07:41:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:41:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:41:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:41:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:42:29 +0300] Report: clients=0
D [19/Oct/2011:07:42:29 +0300] Report: jobs=50
D [19/Oct/2011:07:42:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:42:29 +0300] Report: printers=1
D [19/Oct/2011:07:42:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:42:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:42:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:42:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:43:29 +0300] Report: clients=0
D [19/Oct/2011:07:43:29 +0300] Report: jobs=50
D [19/Oct/2011:07:43:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:43:29 +0300] Report: printers=1
D [19/Oct/2011:07:43:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:43:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:43:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:43:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:44:29 +0300] Report: clients=0
D [19/Oct/2011:07:44:29 +0300] Report: jobs=50
D [19/Oct/2011:07:44:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:44:29 +0300] Report: printers=1
D [19/Oct/2011:07:44:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:44:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:44:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:44:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:45:29 +0300] Report: clients=0
D [19/Oct/2011:07:45:29 +0300] Report: jobs=50
D [19/Oct/2011:07:45:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:45:29 +0300] Report: printers=1
D [19/Oct/2011:07:45:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:45:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:45:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:45:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:46:29 +0300] Report: clients=0
D [19/Oct/2011:07:46:29 +0300] Report: jobs=50
D [19/Oct/2011:07:46:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:46:29 +0300] Report: printers=1
D [19/Oct/2011:07:46:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:46:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:46:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:46:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:47:29 +0300] Report: clients=0
D [19/Oct/2011:07:47:29 +0300] Report: jobs=50
D [19/Oct/2011:07:47:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:47:29 +0300] Report: printers=1
D [19/Oct/2011:07:47:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:47:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:47:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:47:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:48:29 +0300] Report: clients=0
D [19/Oct/2011:07:48:29 +0300] Report: jobs=50
D [19/Oct/2011:07:48:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:48:29 +0300] Report: printers=1
D [19/Oct/2011:07:48:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:48:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:48:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:48:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:49:29 +0300] Report: clients=0
D [19/Oct/2011:07:49:29 +0300] Report: jobs=50
D [19/Oct/2011:07:49:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:49:29 +0300] Report: printers=1
D [19/Oct/2011:07:49:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:49:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:49:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:49:29 +0300] Report: stringpool-total-bytes=79768
D [19/Oct/2011:07:50:29 +0300] Report: clients=0
D [19/Oct/2011:07:50:29 +0300] Report: jobs=50
D [19/Oct/2011:07:50:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:50:29 +0300] Report: printers=1
D [19/Oct/2011:07:50:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:50:29 +0300] Report: stringpool-string-count=4333
D [19/Oct/2011:07:50:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:50:29 +0300] Report: stringpool-total-bytes=79768
I [19/Oct/2011:07:50:50 +0300] Subscription 116 has expired...
D [19/Oct/2011:07:50:50 +0300] cupsdMarkDirty(-----S)
D [19/Oct/2011:07:50:50 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [19/Oct/2011:07:51:20 +0300] Saving subscriptions.conf...
D [19/Oct/2011:07:51:20 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Oct/2011:07:51:29 +0300] Report: clients=0
D [19/Oct/2011:07:51:29 +0300] Report: jobs=50
D [19/Oct/2011:07:51:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:51:29 +0300] Report: printers=1
D [19/Oct/2011:07:51:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:51:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:51:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:51:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:52:29 +0300] Report: clients=0
D [19/Oct/2011:07:52:29 +0300] Report: jobs=50
D [19/Oct/2011:07:52:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:52:29 +0300] Report: printers=1
D [19/Oct/2011:07:52:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:52:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:52:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:52:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:53:29 +0300] Report: clients=0
D [19/Oct/2011:07:53:29 +0300] Report: jobs=50
D [19/Oct/2011:07:53:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:53:29 +0300] Report: printers=1
D [19/Oct/2011:07:53:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:53:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:53:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:53:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:54:29 +0300] Report: clients=0
D [19/Oct/2011:07:54:29 +0300] Report: jobs=50
D [19/Oct/2011:07:54:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:54:29 +0300] Report: printers=1
D [19/Oct/2011:07:54:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:54:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:54:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:54:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:55:29 +0300] Report: clients=0
D [19/Oct/2011:07:55:29 +0300] Report: jobs=50
D [19/Oct/2011:07:55:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:55:29 +0300] Report: printers=1
D [19/Oct/2011:07:55:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:55:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:55:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:55:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:56:29 +0300] Report: clients=0
D [19/Oct/2011:07:56:29 +0300] Report: jobs=50
D [19/Oct/2011:07:56:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:56:29 +0300] Report: printers=1
D [19/Oct/2011:07:56:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:56:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:56:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:56:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:57:29 +0300] Report: clients=0
D [19/Oct/2011:07:57:29 +0300] Report: jobs=50
D [19/Oct/2011:07:57:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:57:29 +0300] Report: printers=1
D [19/Oct/2011:07:57:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:57:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:57:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:57:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:58:29 +0300] Report: clients=0
D [19/Oct/2011:07:58:29 +0300] Report: jobs=50
D [19/Oct/2011:07:58:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:58:29 +0300] Report: printers=1
D [19/Oct/2011:07:58:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:58:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:58:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:58:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:07:59:29 +0300] Report: clients=0
D [19/Oct/2011:07:59:29 +0300] Report: jobs=50
D [19/Oct/2011:07:59:29 +0300] Report: jobs-active=0
D [19/Oct/2011:07:59:29 +0300] Report: printers=1
D [19/Oct/2011:07:59:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:07:59:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:07:59:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:07:59:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:08:00:29 +0300] Report: clients=0
D [19/Oct/2011:08:00:29 +0300] Report: jobs=50
D [19/Oct/2011:08:00:29 +0300] Report: jobs-active=0
D [19/Oct/2011:08:00:29 +0300] Report: printers=1
D [19/Oct/2011:08:00:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:08:00:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:08:00:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:08:00:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:08:01:29 +0300] Report: clients=0
D [19/Oct/2011:08:01:29 +0300] Report: jobs=50
D [19/Oct/2011:08:01:29 +0300] Report: jobs-active=0
D [19/Oct/2011:08:01:29 +0300] Report: printers=1
D [19/Oct/2011:08:01:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:08:01:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:08:01:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:08:01:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:08:02:29 +0300] Report: clients=0
D [19/Oct/2011:08:02:29 +0300] Report: jobs=50
D [19/Oct/2011:08:02:29 +0300] Report: jobs-active=0
D [19/Oct/2011:08:02:29 +0300] Report: printers=1
D [19/Oct/2011:08:02:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:08:02:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:08:02:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:08:02:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:08:03:29 +0300] Report: clients=0
D [19/Oct/2011:08:03:29 +0300] Report: jobs=50
D [19/Oct/2011:08:03:29 +0300] Report: jobs-active=0
D [19/Oct/2011:08:03:29 +0300] Report: printers=1
D [19/Oct/2011:08:03:29 +0300] Report: printers-implicit=0
D [19/Oct/2011:08:03:29 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:08:03:29 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:08:03:29 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:08:20:10 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:08:20:10 +0300] Report: clients=1
D [19/Oct/2011:08:20:10 +0300] Report: jobs=50
D [19/Oct/2011:08:20:10 +0300] Report: jobs-active=0
D [19/Oct/2011:08:20:10 +0300] Report: printers=1
D [19/Oct/2011:08:20:10 +0300] Report: printers-implicit=0
D [19/Oct/2011:08:20:10 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:08:20:10 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:08:20:10 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:08:20:10 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:08:20:10 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:08:20:10 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:08:20:10 +0300] cupsdReadClient: 253 1.1 Renew-Subscription 1
D [19/Oct/2011:08:20:10 +0300] Renew-Subscription /
D [19/Oct/2011:08:20:10 +0300] cupsdIsAuthorized: requesting-user-name="tjaalton"
D [19/Oct/2011:08:20:10 +0300] cupsdMarkDirty(-----S)
D [19/Oct/2011:08:20:10 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Oct/2011:08:20:10 +0300] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [19/Oct/2011:08:20:10 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:08:20:10 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:08:20:10 +0300] cupsdCloseClient: 253
D [19/Oct/2011:08:20:10 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Oct/2011:08:20:41 +0300] Saving subscriptions.conf...
D [19/Oct/2011:08:20:41 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Oct/2011:08:36:10 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:08:36:10 +0300] Report: clients=1
D [19/Oct/2011:08:36:10 +0300] Report: jobs=50
D [19/Oct/2011:08:36:10 +0300] Report: jobs-active=0
D [19/Oct/2011:08:36:10 +0300] Report: printers=1
D [19/Oct/2011:08:36:10 +0300] Report: printers-implicit=0
D [19/Oct/2011:08:36:10 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:08:36:10 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:08:36:10 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:08:36:10 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:08:36:10 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:08:36:10 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:08:36:10 +0300] cupsdReadClient: 253 1.1 Renew-Subscription 1
D [19/Oct/2011:08:36:10 +0300] Renew-Subscription /
D [19/Oct/2011:08:36:10 +0300] cupsdIsAuthorized: requesting-user-name="aino"
D [19/Oct/2011:08:36:10 +0300] cupsdMarkDirty(-----S)
D [19/Oct/2011:08:36:10 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Oct/2011:08:36:10 +0300] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [19/Oct/2011:08:36:10 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:08:36:10 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:08:36:10 +0300] cupsdCloseClient: 253
D [19/Oct/2011:08:36:10 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Oct/2011:08:36:41 +0300] Saving subscriptions.conf...
D [19/Oct/2011:08:36:41 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Oct/2011:09:18:30 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:09:18:30 +0300] Report: clients=1
D [19/Oct/2011:09:18:30 +0300] Report: jobs=50
D [19/Oct/2011:09:18:30 +0300] Report: jobs-active=0
D [19/Oct/2011:09:18:30 +0300] Report: printers=1
D [19/Oct/2011:09:18:30 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:18:30 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:18:30 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:18:30 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:18:30 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:09:18:30 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:09:18:30 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:09:18:30 +0300] cupsdReadClient: 253 1.1 Renew-Subscription 1
D [19/Oct/2011:09:18:30 +0300] Renew-Subscription /
D [19/Oct/2011:09:18:30 +0300] cupsdIsAuthorized: requesting-user-name="tjaalton"
D [19/Oct/2011:09:18:30 +0300] cupsdMarkDirty(-----S)
D [19/Oct/2011:09:18:30 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Oct/2011:09:18:30 +0300] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [19/Oct/2011:09:18:30 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:09:18:30 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:09:18:30 +0300] cupsdCloseClient: 253
D [19/Oct/2011:09:18:30 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Oct/2011:09:19:01 +0300] Saving subscriptions.conf...
D [19/Oct/2011:09:19:01 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Oct/2011:09:28:27 +0300] Report: clients=0
D [19/Oct/2011:09:28:27 +0300] Report: jobs=50
D [19/Oct/2011:09:28:27 +0300] Report: jobs-active=0
D [19/Oct/2011:09:28:27 +0300] Report: printers=1
D [19/Oct/2011:09:28:27 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:28:27 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:28:27 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:28:27 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:29:27 +0300] Report: clients=0
D [19/Oct/2011:09:29:27 +0300] Report: jobs=50
D [19/Oct/2011:09:29:27 +0300] Report: jobs-active=0
D [19/Oct/2011:09:29:27 +0300] Report: printers=1
D [19/Oct/2011:09:29:27 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:29:27 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:29:27 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:29:27 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:30:27 +0300] Report: clients=0
D [19/Oct/2011:09:30:27 +0300] Report: jobs=50
D [19/Oct/2011:09:30:27 +0300] Report: jobs-active=0
D [19/Oct/2011:09:30:27 +0300] Report: printers=1
D [19/Oct/2011:09:30:27 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:30:27 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:30:27 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:30:27 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:31:27 +0300] Report: clients=0
D [19/Oct/2011:09:31:27 +0300] Report: jobs=50
D [19/Oct/2011:09:31:27 +0300] Report: jobs-active=0
D [19/Oct/2011:09:31:27 +0300] Report: printers=1
D [19/Oct/2011:09:31:27 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:31:27 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:31:27 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:31:27 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:32:27 +0300] Report: clients=0
D [19/Oct/2011:09:32:27 +0300] Report: jobs=50
D [19/Oct/2011:09:32:27 +0300] Report: jobs-active=0
D [19/Oct/2011:09:32:27 +0300] Report: printers=1
D [19/Oct/2011:09:32:27 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:32:27 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:32:27 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:32:27 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:33:27 +0300] Report: clients=0
D [19/Oct/2011:09:33:27 +0300] Report: jobs=50
D [19/Oct/2011:09:33:27 +0300] Report: jobs-active=0
D [19/Oct/2011:09:33:27 +0300] Report: printers=1
D [19/Oct/2011:09:33:27 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:33:27 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:33:27 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:33:27 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:34:18 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:09:34:18 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:09:34:18 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:09:34:18 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:09:34:18 +0300] cupsdReadClient: 253 1.1 CUPS-Get-Printers 1
D [19/Oct/2011:09:34:18 +0300] CUPS-Get-Printers
D [19/Oct/2011:09:34:18 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [19/Oct/2011:09:34:18 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [19/Oct/2011:09:34:18 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:09:34:18 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:09:34:18 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:09:34:18 +0300] cupsdReadClient: 253 1.1 CUPS-Get-Default 1
D [19/Oct/2011:09:34:18 +0300] CUPS-Get-Default
D [19/Oct/2011:09:34:18 +0300] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [19/Oct/2011:09:34:18 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [19/Oct/2011:09:34:18 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:09:34:18 +0300] cupsdCloseClient: 253
D [19/Oct/2011:09:34:18 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [19/Oct/2011:09:34:30 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:09:34:30 +0300] Report: clients=1
D [19/Oct/2011:09:34:30 +0300] Report: jobs=50
D [19/Oct/2011:09:34:30 +0300] Report: jobs-active=0
D [19/Oct/2011:09:34:30 +0300] Report: printers=1
D [19/Oct/2011:09:34:30 +0300] Report: printers-implicit=0
D [19/Oct/2011:09:34:30 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:09:34:30 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:09:34:30 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:09:34:30 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:09:34:30 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:09:34:30 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:09:34:30 +0300] cupsdReadClient: 253 1.1 Renew-Subscription 1
D [19/Oct/2011:09:34:30 +0300] Renew-Subscription /
D [19/Oct/2011:09:34:30 +0300] cupsdIsAuthorized: requesting-user-name="aino"
D [19/Oct/2011:09:34:30 +0300] cupsdMarkDirty(-----S)
D [19/Oct/2011:09:34:30 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Oct/2011:09:34:30 +0300] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [19/Oct/2011:09:34:30 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:09:34:30 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:09:34:30 +0300] cupsdCloseClient: 253
D [19/Oct/2011:09:34:30 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Oct/2011:09:35:01 +0300] Saving subscriptions.conf...
D [19/Oct/2011:09:35:01 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Oct/2011:10:16:50 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:10:16:50 +0300] Report: clients=1
D [19/Oct/2011:10:16:50 +0300] Report: jobs=50
D [19/Oct/2011:10:16:50 +0300] Report: jobs-active=0
D [19/Oct/2011:10:16:50 +0300] Report: printers=1
D [19/Oct/2011:10:16:50 +0300] Report: printers-implicit=0
D [19/Oct/2011:10:16:50 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:10:16:50 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:10:16:50 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:10:16:50 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:10:16:50 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:10:16:50 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:10:16:50 +0300] cupsdReadClient: 253 1.1 Renew-Subscription 1
D [19/Oct/2011:10:16:50 +0300] Renew-Subscription /
D [19/Oct/2011:10:16:50 +0300] cupsdIsAuthorized: requesting-user-name="tjaalton"
D [19/Oct/2011:10:16:50 +0300] cupsdMarkDirty(-----S)
D [19/Oct/2011:10:16:50 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Oct/2011:10:16:50 +0300] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [19/Oct/2011:10:16:50 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:10:16:50 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:10:16:50 +0300] cupsdCloseClient: 253
D [19/Oct/2011:10:16:50 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Oct/2011:10:17:21 +0300] Saving subscriptions.conf...
D [19/Oct/2011:10:17:21 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Oct/2011:10:32:50 +0300] cupsdAcceptClient: 253 from localhost (Domain)
D [19/Oct/2011:10:32:50 +0300] Report: clients=1
D [19/Oct/2011:10:32:50 +0300] Report: jobs=50
D [19/Oct/2011:10:32:50 +0300] Report: jobs-active=0
D [19/Oct/2011:10:32:50 +0300] Report: printers=1
D [19/Oct/2011:10:32:50 +0300] Report: printers-implicit=0
D [19/Oct/2011:10:32:50 +0300] Report: stringpool-string-count=4332
D [19/Oct/2011:10:32:50 +0300] Report: stringpool-alloc-bytes=11112
D [19/Oct/2011:10:32:50 +0300] Report: stringpool-total-bytes=79752
D [19/Oct/2011:10:32:50 +0300] cupsdReadClient: 253 POST / HTTP/1.1
D [19/Oct/2011:10:32:50 +0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Oct/2011:10:32:50 +0300] cupsdAuthorize: No authentication data provided.
D [19/Oct/2011:10:32:50 +0300] cupsdReadClient: 253 1.1 Renew-Subscription 1
D [19/Oct/2011:10:32:50 +0300] Renew-Subscription /
D [19/Oct/2011:10:32:50 +0300] cupsdIsAuthorized: requesting-user-name="aino"
D [19/Oct/2011:10:32:50 +0300] cupsdMarkDirty(-----S)
D [19/Oct/2011:10:32:50 +0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Oct/2011:10:32:50 +0300] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [19/Oct/2011:10:32:50 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Oct/2011:10:32:50 +0300] cupsdReadClient: 253 WAITING Closing on EOF
D [19/Oct/2011:10:32:50 +0300] cupsdCloseClient: 253
D [19/Oct/2011:10:32:50 +0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Oct/2011:10:33:21 +0300] Saving subscriptions.conf...
D [19/Oct/2011:10:33:21 +0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"