E [07/Jan/2012:12:15:25 +0100] Unknown directive JobPrivateAccess on line 87.
E [07/Jan/2012:12:15:25 +0100] Unknown directive JobPrivateValues on line 88.
E [07/Jan/2012:12:15:25 +0100] Unknown directive SubscriptionPrivateAccess on line 89.
E [07/Jan/2012:12:15:25 +0100] Unknown directive SubscriptionPrivateValues on line 90.
W [07/Jan/2012:12:15:27 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__' has already been added
W [07/Jan/2012:12:15:27 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__' has already been added
W [07/Jan/2012:12:15:27 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__' has already been added
W [07/Jan/2012:12:15:27 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__' has already been added
W [07/Jan/2012:12:15:27 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__' has already been added
W [07/Jan/2012:12:15:27 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__' has already been added
W [07/Jan/2012:12:15:27 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__' has already been added
I [07/Jan/2012:12:25:22 +0100] Listening to [v1.::1]:631 (IPv6)
I [07/Jan/2012:12:25:22 +0100] Listening to 127.0.0.1:631 (IPv4)
I [07/Jan/2012:12:25:22 +0100] Listening to /var/run/cups/cups.sock (Domain)
E [07/Jan/2012:12:25:22 +0100] Unknown directive JobPrivateAccess on line 88.
E [07/Jan/2012:12:25:22 +0100] Unknown directive JobPrivateValues on line 89.
E [07/Jan/2012:12:25:22 +0100] Unknown directive SubscriptionPrivateAccess on line 90.
E [07/Jan/2012:12:25:22 +0100] Unknown directive SubscriptionPrivateValues on line 91.
I [07/Jan/2012:12:25:22 +0100] Remote access is disabled.
D [07/Jan/2012:12:25:22 +0100] Added auto ServerAlias mezgrman
I [07/Jan/2012:12:25:22 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [07/Jan/2012:12:25:22 +0100] Using default TempDir of /var/spool/cups/tmp...
I [07/Jan/2012:12:25:22 +0100] Configured for up to 100 clients.
I [07/Jan/2012:12:25:22 +0100] Allowing up to 100 client connections per host.
I [07/Jan/2012:12:25:22 +0100] Using policy "default" as the default.
D [07/Jan/2012:12:25:22 +0100] load_ppd: Loading /var/cache/cups/HL-2030-series.data...
D [07/Jan/2012:12:25:23 +0100] Calling DeleteDevice(cups-HL-2030-series)
D [07/Jan/2012:12:25:23 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:25:23 +0100] Using profile id of HL-2030-series-Gray..
D [07/Jan/2012:12:25:23 +0100] Calling CreateProfile(HL-2030-series-Gray..,temp)
D [07/Jan/2012:12:25:23 +0100] created profile /org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__
I [07/Jan/2012:12:25:23 +0100] Registering ICC color profiles for "HL-2030-series"
D [07/Jan/2012:12:25:23 +0100] Calling CreateDevice(cups-HL-2030-series,temp)
D [07/Jan/2012:12:25:23 +0100] created device /org/freedesktop/ColorManager/devices/cups_HL_2030_series
D [07/Jan/2012:12:25:23 +0100] Calling /org/freedesktop/ColorManager/devices/cups_HL_2030_series:AddProfile(/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__) [soft]
W [07/Jan/2012:12:25:23 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__' has already been added
D [07/Jan/2012:12:25:23 +0100] cupsdRegisterPrinter(p=0x211f2780(HL-2030-series))
D [07/Jan/2012:12:25:23 +0100] load_ppd: Loading /var/cache/cups/K-Block-Drucker.data...
D [07/Jan/2012:12:25:23 +0100] Calling DeleteDevice(cups-K-Block-Drucker)
D [07/Jan/2012:12:25:23 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:25:23 +0100] Using profile id of K-Block-Drucker-Gray..
D [07/Jan/2012:12:25:23 +0100] Calling CreateProfile(K-Block-Drucker-Gray..,temp)
D [07/Jan/2012:12:25:23 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__
D [07/Jan/2012:12:25:23 +0100] Using profile id of K-Block-Drucker-RGB..
D [07/Jan/2012:12:25:23 +0100] Calling CreateProfile(K-Block-Drucker-RGB..,temp)
D [07/Jan/2012:12:25:23 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__
I [07/Jan/2012:12:25:23 +0100] Registering ICC color profiles for "K-Block-Drucker"
D [07/Jan/2012:12:25:23 +0100] Calling CreateDevice(cups-K-Block-Drucker,temp)
D [07/Jan/2012:12:25:23 +0100] created device /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker
D [07/Jan/2012:12:25:23 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__) [soft]
W [07/Jan/2012:12:25:23 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__' has already been added
D [07/Jan/2012:12:25:23 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__) [soft]
W [07/Jan/2012:12:25:23 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__' has already been added
D [07/Jan/2012:12:25:23 +0100] cupsdRegisterPrinter(p=0x2122caf0(K-Block-Drucker))
D [07/Jan/2012:12:25:23 +0100] load_ppd: Loading /var/cache/cups/mezgrPrinter.data...
D [07/Jan/2012:12:25:23 +0100] Calling DeleteDevice(cups-mezgrPrinter)
D [07/Jan/2012:12:25:23 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:25:23 +0100] Using profile id of mezgrPrinter-Gray..
D [07/Jan/2012:12:25:23 +0100] Calling CreateProfile(mezgrPrinter-Gray..,temp)
D [07/Jan/2012:12:25:23 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__
D [07/Jan/2012:12:25:23 +0100] Using profile id of mezgrPrinter-RGB..
D [07/Jan/2012:12:25:23 +0100] Calling CreateProfile(mezgrPrinter-RGB..,temp)
D [07/Jan/2012:12:25:23 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__
I [07/Jan/2012:12:25:23 +0100] Registering ICC color profiles for "mezgrPrinter"
D [07/Jan/2012:12:25:23 +0100] Calling CreateDevice(cups-mezgrPrinter,temp)
D [07/Jan/2012:12:25:23 +0100] created device /org/freedesktop/ColorManager/devices/cups_mezgrPrinter
D [07/Jan/2012:12:25:23 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__) [soft]
W [07/Jan/2012:12:25:23 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__' has already been added
D [07/Jan/2012:12:25:23 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__) [soft]
W [07/Jan/2012:12:25:23 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__' has already been added
D [07/Jan/2012:12:25:23 +0100] cupsdRegisterPrinter(p=0x2124cc00(mezgrPrinter))
D [07/Jan/2012:12:25:23 +0100] cupsdMarkDirty(---p--)
D [07/Jan/2012:12:25:23 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [07/Jan/2012:12:25:23 +0100] Partial reload complete.
I [07/Jan/2012:12:25:23 +0100] Listening to [v1.::1]:631 on fd 9...
I [07/Jan/2012:12:25:23 +0100] Listening to 127.0.0.1:631 on fd 10...
I [07/Jan/2012:12:25:23 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [07/Jan/2012:12:25:23 +0100] Resuming new connection processing...
D [07/Jan/2012:12:25:23 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:23 +0100] Discarding unused server-restarted event...
D [07/Jan/2012:12:25:23 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [07/Jan/2012:12:25:23 +0100] Report: clients=1
D [07/Jan/2012:12:25:23 +0100] Report: jobs=10
D [07/Jan/2012:12:25:23 +0100] Report: jobs-active=0
D [07/Jan/2012:12:25:23 +0100] Report: printers=3
D [07/Jan/2012:12:25:23 +0100] Report: printers-implicit=0
D [07/Jan/2012:12:25:23 +0100] Report: stringpool-string-count=11009
D [07/Jan/2012:12:25:23 +0100] Report: stringpool-alloc-bytes=12344
D [07/Jan/2012:12:25:23 +0100] Report: stringpool-total-bytes=201048
D [07/Jan/2012:12:25:31 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [07/Jan/2012:12:25:31 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [07/Jan/2012:12:25:31 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:31 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:31 +0100] cupsdReadClient: 16 1.1 Cancel-Subscription 1
D [07/Jan/2012:12:25:31 +0100] Cancel-Subscription /
D [07/Jan/2012:12:25:31 +0100] cupsdIsAuthorized: requesting-user-name="mezgrman"
D [07/Jan/2012:12:25:31 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:31 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:31 +0100] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [07/Jan/2012:12:25:31 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:31 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [07/Jan/2012:12:25:31 +0100] cupsdCloseClient: 16
D [07/Jan/2012:12:25:31 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:32 +0100] cupsdReadClient: 14 WAITING Closing on EOF
D [07/Jan/2012:12:25:32 +0100] cupsdCloseClient: 14
D [07/Jan/2012:12:25:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 14 POST /admin/ HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 14 1.1 Purge-Jobs 1
D [07/Jan/2012:12:25:49 +0100] Purge-Jobs ipp://localhost/printers/
D [07/Jan/2012:12:25:49 +0100] cupsdIsAuthorized: requesting-user-name="mezgrman"
D [07/Jan/2012:12:25:49 +0100] [Job 1] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] Notifier dbus started - PID = 3961
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 1] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 1] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 2] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 2] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 2] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 3] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 3] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 3] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 4] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 4] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 4] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 5] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 5] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 5] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 6] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 6] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 6] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 7] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 7] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 7] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 8] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 8] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 8] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 9] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 9] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 9] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] [Job 10] Loading attributes...
D [07/Jan/2012:12:25:49 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] [Job 10] Job purged by user.
D [07/Jan/2012:12:25:49 +0100] [Job 10] Unloading...
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:49 +0100] All jobs were purged by "mezgrman".
D [07/Jan/2012:12:25:49 +0100] Returning IPP successful-ok for Purge-Jobs (ipp://localhost/printers/) from localhost
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 14 WAITING Closing on EOF
D [07/Jan/2012:12:25:49 +0100] cupsdCloseClient: 14
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] [Notifier] Connected to D-BUS
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] [Notifier] state=3
D [07/Jan/2012:12:25:49 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 14 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 14 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #1 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/1) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 18 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 18 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 18 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/2
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #2 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/2) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 20 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 20 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 20 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #3 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 21 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 21 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 21 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/4
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #4 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/4) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/5
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #5 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/5) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/6
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #6 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/6) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 24 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/7
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #7 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/7) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/8
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #8 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/8) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 26 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 26 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 26 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/9
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #9 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/9) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAcceptClient: 27 from localhost (Domain)
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 27 POST / HTTP/1.1
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:25:49 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:25:49 +0100] cupsdReadClient: 27 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes ipp://localhost/jobs/10
D [07/Jan/2012:12:25:49 +0100] Get-Job-Attributes client-error-not-found: Job #10 does not exist.
D [07/Jan/2012:12:25:49 +0100] Returning IPP client-error-not-found for Get-Job-Attributes (ipp://localhost/jobs/10) from localhost
D [07/Jan/2012:12:25:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:25:54 +0100] Generating printcap /var/run/cups/printcap...
I [07/Jan/2012:12:25:54 +0100] Saving subscriptions.conf...
D [07/Jan/2012:12:25:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [07/Jan/2012:12:26:38 +0100] cupsdAcceptClient: 28 from localhost (Domain)
D [07/Jan/2012:12:26:38 +0100] Report: clients=11
D [07/Jan/2012:12:26:38 +0100] Report: jobs=0
D [07/Jan/2012:12:26:38 +0100] Report: jobs-active=0
D [07/Jan/2012:12:26:38 +0100] Report: printers=3
D [07/Jan/2012:12:26:38 +0100] Report: printers-implicit=0
D [07/Jan/2012:12:26:38 +0100] Report: stringpool-string-count=11168
D [07/Jan/2012:12:26:38 +0100] Report: stringpool-alloc-bytes=13040
D [07/Jan/2012:12:26:38 +0100] Report: stringpool-total-bytes=204352
D [07/Jan/2012:12:26:38 +0100] cupsdReadClient: 28 GET /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:38 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:26:38 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:26:38 +0100] cupsdIsAuthorized: username=""
D [07/Jan/2012:12:26:38 +0100] cupsdSendHeader: 28 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [07/Jan/2012:12:26:38 +0100] cupsdCloseClient: 28
D [07/Jan/2012:12:26:38 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:26:38 +0100] cupsdAcceptClient: 28 from localhost (Domain)
D [07/Jan/2012:12:26:38 +0100] cupsdReadClient: 28 GET /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:38 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:26:38 +0100] cupsdAuthorize: Authorized as mezgrman using PeerCred
D [07/Jan/2012:12:26:38 +0100] cupsdIsAuthorized: username="mezgrman"
D [07/Jan/2012:12:26:38 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:26:38 +0100] cupsdReadClient: 28 GET /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:38 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:26:38 +0100] cupsdAuthorize: Authorized as mezgrman using PeerCred
D [07/Jan/2012:12:26:38 +0100] cupsdIsAuthorized: username="mezgrman"
D [07/Jan/2012:12:26:38 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:26:38 +0100] cupsdReadClient: 28 PUT /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:38 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:26:38 +0100] cupsdAuthorize: Authorized as mezgrman using PeerCred
D [07/Jan/2012:12:26:38 +0100] cupsdIsAuthorized: username="mezgrman"
I [07/Jan/2012:12:26:38 +0100] Installing config file "/etc/cups/cupsd.conf"...
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 14
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 18
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 20
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 21
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 22
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 23
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 24
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 26
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 27
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:26:39 +0100] cupsdCloseClient: 28
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [07/Jan/2012:12:26:39 +0100] Listening to [v1.::1]:631 (IPv6)
I [07/Jan/2012:12:26:39 +0100] Listening to 127.0.0.1:631 (IPv4)
I [07/Jan/2012:12:26:39 +0100] Listening to /var/run/cups/cups.sock (Domain)
E [07/Jan/2012:12:26:39 +0100] Unknown directive JobPrivateAccess on line 87.
E [07/Jan/2012:12:26:39 +0100] Unknown directive JobPrivateValues on line 88.
E [07/Jan/2012:12:26:39 +0100] Unknown directive SubscriptionPrivateAccess on line 89.
E [07/Jan/2012:12:26:39 +0100] Unknown directive SubscriptionPrivateValues on line 90.
I [07/Jan/2012:12:26:39 +0100] Remote access is disabled.
D [07/Jan/2012:12:26:39 +0100] Added auto ServerAlias mezgrman
I [07/Jan/2012:12:26:39 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [07/Jan/2012:12:26:39 +0100] Using default TempDir of /var/spool/cups/tmp...
I [07/Jan/2012:12:26:39 +0100] Configured for up to 100 clients.
I [07/Jan/2012:12:26:39 +0100] Allowing up to 100 client connections per host.
I [07/Jan/2012:12:26:39 +0100] Using policy "default" as the default.
D [07/Jan/2012:12:26:39 +0100] load_ppd: Loading /var/cache/cups/HL-2030-series.data...
D [07/Jan/2012:12:26:39 +0100] Calling DeleteDevice(cups-HL-2030-series)
D [07/Jan/2012:12:26:39 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:26:39 +0100] Using profile id of HL-2030-series-Gray..
D [07/Jan/2012:12:26:39 +0100] Calling CreateProfile(HL-2030-series-Gray..,temp)
D [07/Jan/2012:12:26:39 +0100] created profile /org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__
I [07/Jan/2012:12:26:39 +0100] Registering ICC color profiles for "HL-2030-series"
D [07/Jan/2012:12:26:39 +0100] Calling CreateDevice(cups-HL-2030-series,temp)
D [07/Jan/2012:12:26:39 +0100] created device /org/freedesktop/ColorManager/devices/cups_HL_2030_series
D [07/Jan/2012:12:26:39 +0100] Calling /org/freedesktop/ColorManager/devices/cups_HL_2030_series:AddProfile(/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__) [soft]
W [07/Jan/2012:12:26:39 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__' has already been added
D [07/Jan/2012:12:26:39 +0100] cupsdRegisterPrinter(p=0x211f2780(HL-2030-series))
D [07/Jan/2012:12:26:39 +0100] load_ppd: Loading /var/cache/cups/K-Block-Drucker.data...
D [07/Jan/2012:12:26:39 +0100] Calling DeleteDevice(cups-K-Block-Drucker)
D [07/Jan/2012:12:26:39 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:26:39 +0100] Using profile id of K-Block-Drucker-Gray..
D [07/Jan/2012:12:26:39 +0100] Calling CreateProfile(K-Block-Drucker-Gray..,temp)
D [07/Jan/2012:12:26:39 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__
D [07/Jan/2012:12:26:39 +0100] Using profile id of K-Block-Drucker-RGB..
D [07/Jan/2012:12:26:39 +0100] Calling CreateProfile(K-Block-Drucker-RGB..,temp)
D [07/Jan/2012:12:26:39 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__
I [07/Jan/2012:12:26:39 +0100] Registering ICC color profiles for "K-Block-Drucker"
D [07/Jan/2012:12:26:39 +0100] Calling CreateDevice(cups-K-Block-Drucker,temp)
D [07/Jan/2012:12:26:39 +0100] created device /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker
D [07/Jan/2012:12:26:39 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__) [soft]
W [07/Jan/2012:12:26:39 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__' has already been added
D [07/Jan/2012:12:26:39 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__) [soft]
W [07/Jan/2012:12:26:39 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__' has already been added
D [07/Jan/2012:12:26:39 +0100] cupsdRegisterPrinter(p=0x2122caf0(K-Block-Drucker))
D [07/Jan/2012:12:26:39 +0100] load_ppd: Loading /var/cache/cups/mezgrPrinter.data...
D [07/Jan/2012:12:26:39 +0100] Calling DeleteDevice(cups-mezgrPrinter)
D [07/Jan/2012:12:26:39 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:26:39 +0100] Using profile id of mezgrPrinter-Gray..
D [07/Jan/2012:12:26:39 +0100] Calling CreateProfile(mezgrPrinter-Gray..,temp)
D [07/Jan/2012:12:26:39 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__
D [07/Jan/2012:12:26:39 +0100] Using profile id of mezgrPrinter-RGB..
D [07/Jan/2012:12:26:39 +0100] Calling CreateProfile(mezgrPrinter-RGB..,temp)
D [07/Jan/2012:12:26:39 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__
I [07/Jan/2012:12:26:39 +0100] Registering ICC color profiles for "mezgrPrinter"
D [07/Jan/2012:12:26:39 +0100] Calling CreateDevice(cups-mezgrPrinter,temp)
D [07/Jan/2012:12:26:39 +0100] created device /org/freedesktop/ColorManager/devices/cups_mezgrPrinter
D [07/Jan/2012:12:26:39 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__) [soft]
W [07/Jan/2012:12:26:39 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__' has already been added
D [07/Jan/2012:12:26:39 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__) [soft]
W [07/Jan/2012:12:26:39 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__' has already been added
D [07/Jan/2012:12:26:39 +0100] cupsdRegisterPrinter(p=0x2124cc00(mezgrPrinter))
D [07/Jan/2012:12:26:39 +0100] cupsdMarkDirty(---p--)
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [07/Jan/2012:12:26:39 +0100] Partial reload complete.
I [07/Jan/2012:12:26:39 +0100] Listening to [v1.::1]:631 on fd 9...
I [07/Jan/2012:12:26:39 +0100] Listening to 127.0.0.1:631 on fd 10...
I [07/Jan/2012:12:26:39 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [07/Jan/2012:12:26:39 +0100] Resuming new connection processing...
D [07/Jan/2012:12:26:39 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:26:39 +0100] Discarding unused server-restarted event...
D [07/Jan/2012:12:26:39 +0100] PID 3961 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [07/Jan/2012:12:26:52 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [07/Jan/2012:12:26:52 +0100] cupsdReadClient: 14 GET /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:52 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:26:52 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:26:52 +0100] cupsdIsAuthorized: username=""
D [07/Jan/2012:12:26:52 +0100] cupsdSendHeader: 14 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [07/Jan/2012:12:26:52 +0100] cupsdCloseClient: 14
D [07/Jan/2012:12:26:52 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:26:52 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [07/Jan/2012:12:26:52 +0100] cupsdReadClient: 14 GET /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:52 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:26:52 +0100] cupsdAuthorize: Authorized as mezgrman using PeerCred
D [07/Jan/2012:12:26:52 +0100] cupsdIsAuthorized: username="mezgrman"
D [07/Jan/2012:12:26:52 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:26:52 +0100] cupsdReadClient: 14 GET /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:52 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:26:52 +0100] cupsdAuthorize: Authorized as mezgrman using PeerCred
D [07/Jan/2012:12:26:52 +0100] cupsdIsAuthorized: username="mezgrman"
D [07/Jan/2012:12:26:52 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:26:52 +0100] cupsdReadClient: 14 PUT /admin/conf/cupsd.conf HTTP/1.1
D [07/Jan/2012:12:26:52 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:26:52 +0100] cupsdAuthorize: Authorized as mezgrman using PeerCred
D [07/Jan/2012:12:26:52 +0100] cupsdIsAuthorized: username="mezgrman"
I [07/Jan/2012:12:26:52 +0100] Installing config file "/etc/cups/cupsd.conf"...
D [07/Jan/2012:12:26:53 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:26:53 +0100] cupsdCloseClient: 14
D [07/Jan/2012:12:26:53 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [07/Jan/2012:12:26:53 +0100] Generating printcap /var/run/cups/printcap...
D [07/Jan/2012:12:26:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [07/Jan/2012:12:26:53 +0100] Listening to [v1.::1]:631 (IPv6)
I [07/Jan/2012:12:26:53 +0100] Listening to 127.0.0.1:631 (IPv4)
I [07/Jan/2012:12:26:53 +0100] Listening to /var/run/cups/cups.sock (Domain)
E [07/Jan/2012:12:26:53 +0100] Unknown directive JobPrivateAccess on line 87.
E [07/Jan/2012:12:26:53 +0100] Unknown directive JobPrivateValues on line 88.
E [07/Jan/2012:12:26:53 +0100] Unknown directive SubscriptionPrivateAccess on line 89.
E [07/Jan/2012:12:26:53 +0100] Unknown directive SubscriptionPrivateValues on line 90.
I [07/Jan/2012:12:26:53 +0100] Remote access is disabled.
D [07/Jan/2012:12:26:53 +0100] Added auto ServerAlias mezgrman
I [07/Jan/2012:12:26:53 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [07/Jan/2012:12:26:53 +0100] Using default TempDir of /var/spool/cups/tmp...
I [07/Jan/2012:12:26:53 +0100] Configured for up to 100 clients.
I [07/Jan/2012:12:26:53 +0100] Allowing up to 100 client connections per host.
I [07/Jan/2012:12:26:53 +0100] Using policy "default" as the default.
D [07/Jan/2012:12:26:53 +0100] load_ppd: Loading /var/cache/cups/HL-2030-series.data...
D [07/Jan/2012:12:26:53 +0100] Calling DeleteDevice(cups-HL-2030-series)
D [07/Jan/2012:12:26:53 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:26:53 +0100] Using profile id of HL-2030-series-Gray..
D [07/Jan/2012:12:26:53 +0100] Calling CreateProfile(HL-2030-series-Gray..,temp)
D [07/Jan/2012:12:26:53 +0100] created profile /org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__
I [07/Jan/2012:12:26:53 +0100] Registering ICC color profiles for "HL-2030-series"
D [07/Jan/2012:12:26:53 +0100] Calling CreateDevice(cups-HL-2030-series,temp)
D [07/Jan/2012:12:26:53 +0100] created device /org/freedesktop/ColorManager/devices/cups_HL_2030_series
D [07/Jan/2012:12:26:53 +0100] Calling /org/freedesktop/ColorManager/devices/cups_HL_2030_series:AddProfile(/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__) [soft]
W [07/Jan/2012:12:26:53 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__' has already been added
D [07/Jan/2012:12:26:53 +0100] cupsdRegisterPrinter(p=0x211f2780(HL-2030-series))
D [07/Jan/2012:12:26:53 +0100] load_ppd: Loading /var/cache/cups/K-Block-Drucker.data...
D [07/Jan/2012:12:26:53 +0100] Calling DeleteDevice(cups-K-Block-Drucker)
D [07/Jan/2012:12:26:53 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:26:53 +0100] Using profile id of K-Block-Drucker-Gray..
D [07/Jan/2012:12:26:53 +0100] Calling CreateProfile(K-Block-Drucker-Gray..,temp)
D [07/Jan/2012:12:26:53 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__
D [07/Jan/2012:12:26:53 +0100] Using profile id of K-Block-Drucker-RGB..
D [07/Jan/2012:12:26:53 +0100] Calling CreateProfile(K-Block-Drucker-RGB..,temp)
D [07/Jan/2012:12:26:53 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__
I [07/Jan/2012:12:26:53 +0100] Registering ICC color profiles for "K-Block-Drucker"
D [07/Jan/2012:12:26:53 +0100] Calling CreateDevice(cups-K-Block-Drucker,temp)
D [07/Jan/2012:12:26:53 +0100] created device /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker
D [07/Jan/2012:12:26:53 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__) [soft]
W [07/Jan/2012:12:26:53 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__' has already been added
D [07/Jan/2012:12:26:53 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__) [soft]
W [07/Jan/2012:12:26:53 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__' has already been added
D [07/Jan/2012:12:26:53 +0100] cupsdRegisterPrinter(p=0x2122caf0(K-Block-Drucker))
D [07/Jan/2012:12:26:53 +0100] load_ppd: Loading /var/cache/cups/mezgrPrinter.data...
D [07/Jan/2012:12:26:53 +0100] Calling DeleteDevice(cups-mezgrPrinter)
D [07/Jan/2012:12:26:53 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:26:53 +0100] Using profile id of mezgrPrinter-Gray..
D [07/Jan/2012:12:26:53 +0100] Calling CreateProfile(mezgrPrinter-Gray..,temp)
D [07/Jan/2012:12:26:53 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__
D [07/Jan/2012:12:26:53 +0100] Using profile id of mezgrPrinter-RGB..
D [07/Jan/2012:12:26:53 +0100] Calling CreateProfile(mezgrPrinter-RGB..,temp)
D [07/Jan/2012:12:26:53 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__
I [07/Jan/2012:12:26:53 +0100] Registering ICC color profiles for "mezgrPrinter"
D [07/Jan/2012:12:26:53 +0100] Calling CreateDevice(cups-mezgrPrinter,temp)
D [07/Jan/2012:12:26:53 +0100] created device /org/freedesktop/ColorManager/devices/cups_mezgrPrinter
D [07/Jan/2012:12:26:53 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__) [soft]
W [07/Jan/2012:12:26:53 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__' has already been added
D [07/Jan/2012:12:26:53 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__) [soft]
W [07/Jan/2012:12:26:53 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__' has already been added
D [07/Jan/2012:12:26:53 +0100] cupsdRegisterPrinter(p=0x2124cc00(mezgrPrinter))
D [07/Jan/2012:12:26:53 +0100] cupsdMarkDirty(---p--)
D [07/Jan/2012:12:26:53 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [07/Jan/2012:12:26:53 +0100] Partial reload complete.
I [07/Jan/2012:12:26:53 +0100] Listening to [v1.::1]:631 on fd 9...
I [07/Jan/2012:12:26:53 +0100] Listening to 127.0.0.1:631 on fd 10...
I [07/Jan/2012:12:26:53 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [07/Jan/2012:12:26:53 +0100] Resuming new connection processing...
D [07/Jan/2012:12:26:53 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:26:53 +0100] Discarding unused server-restarted event...
I [07/Jan/2012:12:27:08 +0100] Scheduler shutting down normally.
D [07/Jan/2012:12:27:08 +0100] Discarding unused server-stopped event...
I [07/Jan/2012:12:27:08 +0100] Generating printcap /var/run/cups/printcap...
D [07/Jan/2012:12:27:08 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [07/Jan/2012:12:27:08 +0100] Saving job.cache...
I [07/Jan/2012:12:27:09 +0100] Listening to [v1.::1]:631 (IPv6)
I [07/Jan/2012:12:27:09 +0100] Listening to 127.0.0.1:631 (IPv4)
I [07/Jan/2012:12:27:09 +0100] Listening to /var/run/cups/cups.sock (Domain)
E [07/Jan/2012:12:27:09 +0100] Unknown directive JobPrivateAccess on line 87.
E [07/Jan/2012:12:27:09 +0100] Unknown directive JobPrivateValues on line 88.
E [07/Jan/2012:12:27:09 +0100] Unknown directive SubscriptionPrivateAccess on line 89.
E [07/Jan/2012:12:27:09 +0100] Unknown directive SubscriptionPrivateValues on line 90.
I [07/Jan/2012:12:27:09 +0100] Remote access is disabled.
D [07/Jan/2012:12:27:09 +0100] Added auto ServerAlias mezgrman
I [07/Jan/2012:12:27:09 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [07/Jan/2012:12:27:09 +0100] Using default TempDir of /var/spool/cups/tmp...
I [07/Jan/2012:12:27:09 +0100] Configured for up to 100 clients.
I [07/Jan/2012:12:27:09 +0100] Allowing up to 100 client connections per host.
I [07/Jan/2012:12:27:09 +0100] Using policy "default" as the default.
I [07/Jan/2012:12:27:09 +0100] Full reload is required.
I [07/Jan/2012:12:27:09 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 78 filters...
D [07/Jan/2012:12:27:09 +0100] Loading printer HL-2030-series...
D [07/Jan/2012:12:27:09 +0100] load_ppd: Loading /var/cache/cups/HL-2030-series.data...
D [07/Jan/2012:12:27:09 +0100] Calling DeleteDevice(cups-HL-2030-series)
D [07/Jan/2012:12:27:09 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:27:09 +0100] Using profile id of HL-2030-series-Gray..
D [07/Jan/2012:12:27:09 +0100] Calling CreateProfile(HL-2030-series-Gray..,temp)
D [07/Jan/2012:12:27:09 +0100] created profile /org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__
I [07/Jan/2012:12:27:09 +0100] Registering ICC color profiles for "HL-2030-series"
D [07/Jan/2012:12:27:09 +0100] Calling CreateDevice(cups-HL-2030-series,temp)
D [07/Jan/2012:12:27:09 +0100] created device /org/freedesktop/ColorManager/devices/cups_HL_2030_series
D [07/Jan/2012:12:27:09 +0100] Calling /org/freedesktop/ColorManager/devices/cups_HL_2030_series:AddProfile(/org/freedesktop/ColorManager/profiles/HL_2030_series_Gray__) [soft]
D [07/Jan/2012:12:27:09 +0100] cupsdRegisterPrinter(p=0x22e2add8(HL-2030-series))
D [07/Jan/2012:12:27:09 +0100] Loading printer K-Block-Drucker...
D [07/Jan/2012:12:27:09 +0100] load_ppd: Loading /var/cache/cups/K-Block-Drucker.data...
D [07/Jan/2012:12:27:09 +0100] Calling DeleteDevice(cups-K-Block-Drucker)
D [07/Jan/2012:12:27:09 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:27:09 +0100] Using profile id of K-Block-Drucker-Gray..
D [07/Jan/2012:12:27:09 +0100] Calling CreateProfile(K-Block-Drucker-Gray..,temp)
D [07/Jan/2012:12:27:09 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__
D [07/Jan/2012:12:27:09 +0100] Using profile id of K-Block-Drucker-RGB..
D [07/Jan/2012:12:27:09 +0100] Calling CreateProfile(K-Block-Drucker-RGB..,temp)
D [07/Jan/2012:12:27:09 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__
I [07/Jan/2012:12:27:09 +0100] Registering ICC color profiles for "K-Block-Drucker"
D [07/Jan/2012:12:27:09 +0100] Calling CreateDevice(cups-K-Block-Drucker,temp)
D [07/Jan/2012:12:27:09 +0100] created device /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker
D [07/Jan/2012:12:27:09 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__) [soft]
D [07/Jan/2012:12:27:09 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__) [soft]
D [07/Jan/2012:12:27:09 +0100] cupsdRegisterPrinter(p=0x22e89dd0(K-Block-Drucker))
D [07/Jan/2012:12:27:09 +0100] load_ppd: Loading /var/cache/cups/K-Block-Drucker.data...
D [07/Jan/2012:12:27:09 +0100] Calling DeleteDevice(cups-K-Block-Drucker)
D [07/Jan/2012:12:27:09 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:27:09 +0100] Using profile id of K-Block-Drucker-Gray..
D [07/Jan/2012:12:27:09 +0100] Calling CreateProfile(K-Block-Drucker-Gray..,temp)
D [07/Jan/2012:12:27:09 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__
D [07/Jan/2012:12:27:09 +0100] Using profile id of K-Block-Drucker-RGB..
D [07/Jan/2012:12:27:09 +0100] Calling CreateProfile(K-Block-Drucker-RGB..,temp)
D [07/Jan/2012:12:27:09 +0100] created profile /org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__
I [07/Jan/2012:12:27:09 +0100] Registering ICC color profiles for "K-Block-Drucker"
D [07/Jan/2012:12:27:09 +0100] Calling CreateDevice(cups-K-Block-Drucker,temp)
D [07/Jan/2012:12:27:09 +0100] created device /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker
D [07/Jan/2012:12:27:09 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__) [soft]
W [07/Jan/2012:12:27:09 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_Gray__' has already been added
D [07/Jan/2012:12:27:09 +0100] Calling /org/freedesktop/ColorManager/devices/cups_K_Block_Drucker:AddProfile(/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__) [soft]
W [07/Jan/2012:12:27:09 +0100] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/K_Block_Drucker_RGB__' has already been added
D [07/Jan/2012:12:27:09 +0100] cupsdRegisterPrinter(p=0x22e89dd0(K-Block-Drucker))
D [07/Jan/2012:12:27:09 +0100] Loading printer mezgrPrinter...
D [07/Jan/2012:12:27:09 +0100] load_ppd: Loading /var/cache/cups/mezgrPrinter.data...
D [07/Jan/2012:12:27:09 +0100] Calling DeleteDevice(cups-mezgrPrinter)
D [07/Jan/2012:12:27:09 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [07/Jan/2012:12:27:09 +0100] Using profile id of mezgrPrinter-Gray..
D [07/Jan/2012:12:27:09 +0100] Calling CreateProfile(mezgrPrinter-Gray..,temp)
D [07/Jan/2012:12:27:09 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__
D [07/Jan/2012:12:27:09 +0100] Using profile id of mezgrPrinter-RGB..
D [07/Jan/2012:12:27:09 +0100] Calling CreateProfile(mezgrPrinter-RGB..,temp)
D [07/Jan/2012:12:27:09 +0100] created profile /org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__
I [07/Jan/2012:12:27:09 +0100] Registering ICC color profiles for "mezgrPrinter"
D [07/Jan/2012:12:27:09 +0100] Calling CreateDevice(cups-mezgrPrinter,temp)
D [07/Jan/2012:12:27:09 +0100] created device /org/freedesktop/ColorManager/devices/cups_mezgrPrinter
D [07/Jan/2012:12:27:09 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_Gray__) [soft]
D [07/Jan/2012:12:27:09 +0100] Calling /org/freedesktop/ColorManager/devices/cups_mezgrPrinter:AddProfile(/org/freedesktop/ColorManager/profiles/mezgrPrinter_RGB__) [soft]
D [07/Jan/2012:12:27:09 +0100] cupsdRegisterPrinter(p=0x22eaa6a0(mezgrPrinter))
D [07/Jan/2012:12:27:09 +0100] cupsdLoadRemoteCache: Not loading remote cache.
I [07/Jan/2012:12:27:09 +0100] Loading job cache file "/var/cache/cups/job.cache"...
D [07/Jan/2012:12:27:09 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [07/Jan/2012:12:27:09 +0100] Full reload complete.
D [07/Jan/2012:12:27:09 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [07/Jan/2012:12:27:09 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
D [07/Jan/2012:12:27:09 +0100] Removed "/var/spool/cups/tmp/cups-dbus-notifier-lockfile"...
D [07/Jan/2012:12:27:09 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [07/Jan/2012:12:27:09 +0100] Cleaning out old files in "/var/cache/cups"...
I [07/Jan/2012:12:27:09 +0100] Listening to [v1.::1]:631 on fd 9...
I [07/Jan/2012:12:27:09 +0100] Listening to 127.0.0.1:631 on fd 10...
I [07/Jan/2012:12:27:09 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [07/Jan/2012:12:27:09 +0100] Resuming new connection processing...
D [07/Jan/2012:12:27:09 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:27:09 +0100] Discarding unused server-started event...
D [07/Jan/2012:12:27:10 +0100] Report: clients=0
D [07/Jan/2012:12:27:10 +0100] Report: jobs=0
D [07/Jan/2012:12:27:10 +0100] Report: jobs-active=0
D [07/Jan/2012:12:27:10 +0100] Report: printers=3
D [07/Jan/2012:12:27:10 +0100] Report: printers-implicit=0
D [07/Jan/2012:12:27:10 +0100] Report: stringpool-string-count=10517
D [07/Jan/2012:12:27:10 +0100] Report: stringpool-alloc-bytes=12344
D [07/Jan/2012:12:27:10 +0100] Report: stringpool-total-bytes=194440
D [07/Jan/2012:12:29:41 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [07/Jan/2012:12:29:42 +0100] Avahi client started
D [07/Jan/2012:12:29:42 +0100] Report: clients=1
D [07/Jan/2012:12:29:42 +0100] Report: jobs=0
D [07/Jan/2012:12:29:42 +0100] Report: jobs-active=0
D [07/Jan/2012:12:29:42 +0100] Report: printers=3
D [07/Jan/2012:12:29:42 +0100] Report: printers-implicit=0
D [07/Jan/2012:12:29:42 +0100] Report: stringpool-string-count=10517
D [07/Jan/2012:12:29:42 +0100] Report: stringpool-alloc-bytes=12344
D [07/Jan/2012:12:29:42 +0100] Report: stringpool-total-bytes=194440
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 POST / HTTP/1.1
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:29:42 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:42 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:42 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 POST / HTTP/1.1
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:29:42 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [07/Jan/2012:12:29:42 +0100] CUPS-Get-Default
D [07/Jan/2012:12:29:42 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 POST / HTTP/1.1
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:29:42 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:42 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:42 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 POST / HTTP/1.1
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:29:42 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [07/Jan/2012:12:29:42 +0100] CUPS-Get-Default
D [07/Jan/2012:12:29:42 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:29:42 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [07/Jan/2012:12:29:42 +0100] cupsdCloseClient: 16
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:29:42 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:29:42 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:42 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:42 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:29:42 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [07/Jan/2012:12:29:42 +0100] cupsdCloseClient: 16
D [07/Jan/2012:12:29:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:29:53 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [07/Jan/2012:12:29:53 +0100] cupsdCloseClient: 16
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:29:53 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:29:53 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:53 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:53 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [07/Jan/2012:12:29:53 +0100] cupsdCloseClient: 16
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [07/Jan/2012:12:29:53 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 POST /printers/mezgrPrinter HTTP/1.1
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:29:53 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 1.1 Print-Job 1
D [07/Jan/2012:12:29:53 +0100] Print-Job ipp://localhost:631/printers/mezgrPrinter
D [07/Jan/2012:12:29:53 +0100] [Job ???] Auto-typing file...
I [07/Jan/2012:12:29:53 +0100] [Job ???] Request file type is application/pdf.
D [07/Jan/2012:12:29:53 +0100] cupsdMarkDirty(----J-)
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [07/Jan/2012:12:29:53 +0100] add_job: requesting-user-name="mezgrman"
D [07/Jan/2012:12:29:53 +0100] Adding default job-sheets values "none,none"...
I [07/Jan/2012:12:29:53 +0100] [Job 11] Adding start banner page "none".
D [07/Jan/2012:12:29:53 +0100] Notifier dbus started - PID = 4061
D [07/Jan/2012:12:29:53 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:29:53 +0100] cupsdMarkDirty(----J-)
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:29:53 +0100] [Job 11] Adding end banner page "none".
I [07/Jan/2012:12:29:53 +0100] [Job 11] File of type application/pdf queued by "mezgrman".
D [07/Jan/2012:12:29:53 +0100] [Job 11] hold_until=0
I [07/Jan/2012:12:29:53 +0100] [Job 11] Queued on "mezgrPrinter" by "mezgrman".
D [07/Jan/2012:12:29:53 +0100] cupsdMarkDirty(----J-)
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:29:53 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:29:53 +0100] [Job 11] job-sheets=none,none
D [07/Jan/2012:12:29:53 +0100] [Job 11] argv[0]="mezgrPrinter"
D [07/Jan/2012:12:29:53 +0100] [Job 11] argv[1]="11"
D [07/Jan/2012:12:29:53 +0100] [Job 11] argv[2]="mezgrman"
D [07/Jan/2012:12:29:53 +0100] [Job 11] argv[3]="Drucken - www.mezgrman.de/downloads/files/logbuch_micro.pdf"
D [07/Jan/2012:12:29:53 +0100] [Job 11] argv[4]="1"
D [07/Jan/2012:12:29:53 +0100] [Job 11] argv[5]="ColorModel=RGB PageSize=A4 job-uuid=urn:uuid:6afe3793-4b32-3f93-68ec-5d78f9a5f122 job-originating-host-name=localhost time-at-creation=1325935793 time-at-processing=1325935793 AP_D_InputSlot="
D [07/Jan/2012:12:29:53 +0100] [Job 11] argv[6]="/var/spool/cups/d00011-001"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[8]="HOME=/var/spool/cups/tmp"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[10]="SERVER_ADMIN=root@mezgrman"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[11]="SOFTWARE=CUPS/1.5.0"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[13]="USER=root"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[16]="IPP_PORT=631"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[17]="CHARSET=utf-8"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[18]="LANG=de_DE.UTF-8"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[19]="PPD=/etc/cups/ppd/mezgrPrinter.ppd"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[20]="RIP_MAX_CACHE=128m"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[21]="CONTENT_TYPE=application/pdf"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[22]="DEVICE_URI=smb://METZLESHEIMNETZ/GABI/mezgrPrinter"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[23]="PRINTER_INFO=HP Deskjet F2492"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[24]="PRINTER_LOCATION=Wohnzimmer"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[25]="PRINTER=mezgrPrinter"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[26]="PRINTER_STATE_REASONS=none"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[27]="CUPS_FILETYPE=document"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[28]="FINAL_CONTENT_TYPE=printer/mezgrPrinter"
D [07/Jan/2012:12:29:53 +0100] [Job 11] envp[29]="AUTH_I****"
I [07/Jan/2012:12:29:53 +0100] [Job 11] Started filter /usr/lib/cups/filter/pdftopdf (PID 4062)
I [07/Jan/2012:12:29:53 +0100] [Job 11] Started filter /usr/lib/cups/filter/gstoraster (PID 4063)
I [07/Jan/2012:12:29:53 +0100] [Job 11] Started filter /usr/lib/cups/filter/hpcups (PID 4064)
I [07/Jan/2012:12:29:53 +0100] [Job 11] Started backend /usr/lib/cups/backend/smb (PID 4065)
D [07/Jan/2012:12:29:53 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:29:53 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/mezgrPrinter) from localhost
D [07/Jan/2012:12:29:53 +0100] [Notifier] state=3
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [07/Jan/2012:12:29:53 +0100] cupsdCloseClient: 16
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:53 +0100] [Notifier] Connected to D-BUS
D [07/Jan/2012:12:29:53 +0100] [Notifier] JobCreated
D [07/Jan/2012:12:29:53 +0100] [Notifier] state=3
D [07/Jan/2012:12:29:53 +0100] [Notifier] PrinterStateChanged
D [07/Jan/2012:12:29:53 +0100] [Notifier] state=3
D [07/Jan/2012:12:29:53 +0100] [Notifier] JobState
D [07/Jan/2012:12:29:53 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:53 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 16 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:29:53 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:29:53 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:53 +0100] cupsdAcceptClient: 21 from localhost (Domain)
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 21 POST / HTTP/1.1
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:53 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:53 +0100] cupsdReadClient: 21 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:29:53 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:29:53 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:29:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:54 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [07/Jan/2012:12:29:54 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:29:54 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:54 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:54 +0100] cupsdReadClient: 22 1.1 Get-Jobs 1
D [07/Jan/2012:12:29:54 +0100] Get-Jobs ipp://localhost/printers/
D [07/Jan/2012:12:29:54 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [07/Jan/2012:12:29:54 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:54 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [07/Jan/2012:12:29:54 +0100] cupsdCloseClient: 22
D [07/Jan/2012:12:29:54 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:54 +0100] [Job 11] PPD uses qualifier 'RGB.Plain.'
D [07/Jan/2012:12:29:54 +0100] PID 4062 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [07/Jan/2012:12:29:54 +0100] [Job 11] Calling FindDeviceById(mezgrPrinter)
D [07/Jan/2012:12:29:54 +0100] [Job 11] Failed to send: org.freedesktop.DBus.Error.AccessDenied:Rejected send message, 1 matched rules; type="method_call", sender=":1.79" (uid=7 pid=4063 comm="mezgrPrinter 11 mezgrman Drucken - www.mezgrman.de") interface="org.freedesktop.ColorManager" member="FindDeviceById" error name="(unset)" requested_reply="0" destination="org.freedesktop.ColorManager" (uid=102 pid=1042 comm="/usr/lib/i386-linux-gnu/colord/colord ")
D [07/Jan/2012:12:29:54 +0100] [Job 11] Failed to get profile filename!
I [07/Jan/2012:12:29:54 +0100] [Job 11] no profiles specified in PPD
D [07/Jan/2012:12:29:54 +0100] [Job 11] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [07/Jan/2012:12:29:54 +0100] [Job 11] 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=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c -f -_
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[8]="HOME=/var/spool/cups/tmp"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[10]="SERVER_ADMIN=root@mezgrman"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[11]="SOFTWARE=CUPS/1.5.0"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[12]="USER=root"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[15]="IPP_PORT=631"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[16]="CHARSET=utf-8"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[17]="LANG=de_DE.UTF-8"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[18]="PPD=/etc/cups/ppd/mezgrPrinter.ppd"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[19]="RIP_MAX_CACHE=128m"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[20]="CONTENT_TYPE=application/pdf"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[21]="DEVICE_URI=smb://METZLESHEIMNETZ/GABI/mezgrPrinter"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[22]="PRINTER_INFO=HP Deskjet F2492"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[23]="PRINTER_LOCATION=Wohnzimmer"
D [07/Jan/2012:12:29:54 +0100] Discarding unused job-progress event...
D [07/Jan/2012:12:29:54 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:29:54 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[24]="PRINTER=mezgrPrinter"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[25]="PRINTER_STATE_REASONS=none"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[26]="CUPS_FILETYPE=document"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[27]="FINAL_CONTENT_TYPE=printer/mezgrPrinter"
D [07/Jan/2012:12:29:54 +0100] [Job 11] envp[28]="AUTH_INFO_REQUIRED=none"
D [07/Jan/2012:12:29:54 +0100] [Notifier] state=3
D [07/Jan/2012:12:29:54 +0100] [Notifier] PrinterStateChanged
D [07/Jan/2012:12:29:55 +0100] [Job 11] Connected using Kerberos...
D [07/Jan/2012:12:29:55 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [07/Jan/2012:12:29:55 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:29:55 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:55 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:55 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:55 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:55 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:55 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:55 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:29:55 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:55 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:55 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Default 1
D [07/Jan/2012:12:29:55 +0100] CUPS-Get-Default
D [07/Jan/2012:12:29:55 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [07/Jan/2012:12:29:55 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
I [07/Jan/2012:12:29:56 +0100] [Job 11] Start rendering...
D [07/Jan/2012:12:29:56 +0100] [Job 11] Set job-printer-state-message to "Start rendering...", current level=INFO
D [07/Jan/2012:12:29:56 +0100] Discarding unused job-progress event...
D [07/Jan/2012:12:29:56 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:56 +0100] [Notifier] state=3
D [07/Jan/2012:12:29:56 +0100] [Notifier] PrinterStateChanged
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:56 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:56 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:56 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:56 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Default 1
D [07/Jan/2012:12:29:56 +0100] CUPS-Get-Default
D [07/Jan/2012:12:29:56 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
I [07/Jan/2012:12:29:56 +0100] [Job 11] Processing page 1...
D [07/Jan/2012:12:29:56 +0100] [Job 11] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [07/Jan/2012:12:29:56 +0100] Discarding unused job-progress event...
D [07/Jan/2012:12:29:56 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:56 +0100] [Notifier] state=3
D [07/Jan/2012:12:29:56 +0100] [Notifier] PrinterStateChanged
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:56 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:56 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:56 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:56 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:56 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Default 1
D [07/Jan/2012:12:29:56 +0100] CUPS-Get-Default
D [07/Jan/2012:12:29:56 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [07/Jan/2012:12:29:56 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:56 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 1.1 Create-Printer-Subscription 1
D [07/Jan/2012:12:29:58 +0100] Create-Printer-Subscription /
D [07/Jan/2012:12:29:58 +0100] cupsdCreateSubscription(con=0x22eebcf8(24), uri="/")
D [07/Jan/2012:12:29:58 +0100] pullmethod="ippget"
D [07/Jan/2012:12:29:58 +0100] notify-lease-duration=86400
D [07/Jan/2012:12:29:58 +0100] notify-time-interval=0
D [07/Jan/2012:12:29:58 +0100] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [07/Jan/2012:12:29:58 +0100] Added subscription #104 for server.
D [07/Jan/2012:12:29:58 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:58 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:29:58 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 1.1 Get-Printer-Attributes 1
D [07/Jan/2012:12:29:58 +0100] Get-Printer-Attributes ipp://localhost/printers/mezgrPrinter
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/mezgrPrinter) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 WAITING Closing on EOF
D [07/Jan/2012:12:29:58 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 1.1 Get-Printer-Attributes 1
D [07/Jan/2012:12:29:58 +0100] Get-Printer-Attributes ipp://localhost/printers/HL-2030-series
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HL-2030-series) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 WAITING Closing on EOF
D [07/Jan/2012:12:29:58 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 1.1 Get-Printer-Attributes 1
D [07/Jan/2012:12:29:58 +0100] Get-Printer-Attributes ipp://localhost/printers/K-Block-Drucker
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/K-Block-Drucker) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 WAITING Closing on EOF
D [07/Jan/2012:12:29:58 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 WAITING Closing on EOF
D [07/Jan/2012:12:29:58 +0100] cupsdCloseClient: 24
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 1.1 Get-Jobs 1
D [07/Jan/2012:12:29:58 +0100] Get-Jobs ipp://localhost/printers/
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:29:58 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 25 WAITING Closing on EOF
D [07/Jan/2012:12:29:58 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 WAITING Closing on EOF
D [07/Jan/2012:12:29:58 +0100] cupsdCloseClient: 24
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 1.1 Get-Jobs 1
D [07/Jan/2012:12:29:58 +0100] Get-Jobs ipp://localhost/printers/
D [07/Jan/2012:12:29:58 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:58 +0100] cupsdReadClient: 24 WAITING Closing on EOF
D [07/Jan/2012:12:29:58 +0100] cupsdCloseClient: 24
D [07/Jan/2012:12:29:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:59 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [07/Jan/2012:12:29:59 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:29:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:29:59 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:29:59 +0100] cupsdReadClient: 24 1.1 Get-Notifications 1
D [07/Jan/2012:12:29:59 +0100] Get-Notifications /
D [07/Jan/2012:12:29:59 +0100] cupsdIsAuthorized: requesting-user-name="mezgrman"
D [07/Jan/2012:12:29:59 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [07/Jan/2012:12:29:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:29:59 +0100] cupsdReadClient: 24 WAITING Closing on EOF
D [07/Jan/2012:12:29:59 +0100] cupsdCloseClient: 24
D [07/Jan/2012:12:29:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] [Job 11] **** Warning: can't process font stream, loading font by the name.
D [07/Jan/2012:12:30:00 +0100] [Job 11] **** Error reading a content stream. The page may be incomplete.
D [07/Jan/2012:12:30:00 +0100] [Job 11] **** Error reading a content stream. The page may be incomplete.
D [07/Jan/2012:12:30:00 +0100] [Job 11] **** Error reading a content stream. The page may be incomplete.
D [07/Jan/2012:12:30:00 +0100] [Job 11] **** File did not complete the page properly and may be damaged.
D [07/Jan/2012:12:30:00 +0100] [Job 11] Error: /typecheck in --run--
D [07/Jan/2012:12:30:00 +0100] [Job 11] Operand stack:
D [07/Jan/2012:12:30:00 +0100] [Job 11] (/tmp/gs_DXzhv5)   --nostringval--   --dict:7/16(L)--   3   10   5   12   Annots
D [07/Jan/2012:12:30:00 +0100] [Job 11] Execution stack:
D [07/Jan/2012:12:30:00 +0100] [Job 11] %interp_exit   .runexec2   --nostringval--   --nostringval--   --nostringval--   2   %stopped_push   --nostringval--   --nostringval--   --nostringval--   false   1   %stopped_push   1914   1   3   %oparray_pop   1913   1   3   %oparray_pop   1897   1   3   %oparray_pop   --nostringval--   --nostringval--   --nostringval--   2   1   1   --nostringval--   %for_pos_int_continue   --nostringval--   --nostringval--   --nostringval--   --nostringval--
D [07/Jan/2012:12:30:00 +0100] [Job 11] Dictionary stack:
D [07/Jan/2012:12:30:00 +0100] [Job 11] --dict:1172/1684(ro)(G)--   --dict:1/20(G)--   --dict:82/200(L)--   --dict:82/200(L)--   --dict:108/127(ro)(G)--   --dict:291/300(ro)(G)--   --dict:23/30(L)--   --dict:6/8(L)--   --dict:21/40(L)--   --dict:1/1(ro)(G)--   --dict:1/1(ro)(G)--   --dict:1/1(ro)(G)--   --dict:1/1(ro)(G)--   --dict:3/5(L)--   --dict:6/15(L)--
D [07/Jan/2012:12:30:00 +0100] [Job 11] Current allocation mode is local
D [07/Jan/2012:12:30:00 +0100] [Job 11] Last OS error: 2
D [07/Jan/2012:12:30:00 +0100] [Job 11] GPL Ghostscript 9.04: Unrecoverable error, exit code 1
I [07/Jan/2012:12:30:00 +0100] [Job 11] Rendering completed
D [07/Jan/2012:12:30:00 +0100] [Job 11] Set job-printer-state-message to "Rendering completed", current level=INFO
D [07/Jan/2012:12:30:00 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] [Notifier] state=3
D [07/Jan/2012:12:30:00 +0100] [Notifier] PrinterStateChanged
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Printers 1
D [07/Jan/2012:12:30:00 +0100] CUPS-Get-Printers
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Default 1
D [07/Jan/2012:12:30:00 +0100] CUPS-Get-Default
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Jan/2012:12:30:00 +0100] PID 4063 (/usr/lib/cups/filter/gstoraster) exited with no errors.
D [07/Jan/2012:12:30:00 +0100] [Job 11] prnt/hpcups/HPCupsFilter.cpp 511: cupsRasterOpen failed, fd = 0
D [07/Jan/2012:12:30:00 +0100] PID 4064 (/usr/lib/cups/filter/hpcups) stopped with status 1.
D [07/Jan/2012:12:30:00 +0100] PID 4065 (/usr/lib/cups/backend/smb) exited with no errors.
D [07/Jan/2012:12:30:00 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
E [07/Jan/2012:12:30:00 +0100] [Job 11] Job stopped due to filter errors; please consult the error_log file for details.
D [07/Jan/2012:12:30:00 +0100] cupsdMarkDirty(----J-)
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] [Notifier] state=3
D [07/Jan/2012:12:30:00 +0100] [Notifier] JobState
D [07/Jan/2012:12:30:00 +0100] [Notifier] state=3
D [07/Jan/2012:12:30:00 +0100] [Notifier] PrinterStateChanged
D [07/Jan/2012:12:30:00 +0100] cupsdAcceptClient: 19 from localhost (Domain)
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 19 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:30:00 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 24 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 24 1.1 Get-Notifications 1
D [07/Jan/2012:12:30:00 +0100] Get-Notifications /
D [07/Jan/2012:12:30:00 +0100] cupsdIsAuthorized: requesting-user-name="mezgrman"
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 1.1 Get-Printer-Attributes 1
D [07/Jan/2012:12:30:00 +0100] Get-Printer-Attributes ipp://mezgrman/printers/mezgrPrinter
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://mezgrman/printers/mezgrPrinter) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 WAITING Closing on EOF
D [07/Jan/2012:12:30:00 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:30:00 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 WAITING Closing on EOF
D [07/Jan/2012:12:30:00 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:30:00 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAcceptClient: 26 from localhost (Domain)
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 26 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 26 1.1 Get-Printer-Attributes 1
D [07/Jan/2012:12:30:00 +0100] Get-Printer-Attributes ipp://mezgrman/printers/mezgrPrinter
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://mezgrman/printers/mezgrPrinter) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 26 POST / HTTP/1.1
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 26 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:30:00 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:30:00 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:00 +0100] cupsdReadClient: 25 WAITING Closing on EOF
D [07/Jan/2012:12:30:00 +0100] cupsdCloseClient: 25
D [07/Jan/2012:12:30:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:01 +0100] cupsdReadClient: 24 WAITING Closing on EOF
D [07/Jan/2012:12:30:01 +0100] cupsdCloseClient: 24
D [07/Jan/2012:12:30:01 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:01 +0100] [Job 11] Unloading...
I [07/Jan/2012:12:30:24 +0100] Saving job.cache...
I [07/Jan/2012:12:30:24 +0100] Saving subscriptions.conf...
D [07/Jan/2012:12:30:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [07/Jan/2012:12:30:50 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [07/Jan/2012:12:30:50 +0100] Report: clients=8
D [07/Jan/2012:12:30:50 +0100] Report: jobs=1
D [07/Jan/2012:12:30:50 +0100] Report: jobs-active=1
D [07/Jan/2012:12:30:50 +0100] Report: printers=3
D [07/Jan/2012:12:30:50 +0100] Report: printers-implicit=0
D [07/Jan/2012:12:30:50 +0100] Report: stringpool-string-count=10802
D [07/Jan/2012:12:30:50 +0100] Report: stringpool-alloc-bytes=13392
D [07/Jan/2012:12:30:50 +0100] Report: stringpool-total-bytes=200656
D [07/Jan/2012:12:30:50 +0100] cupsdReadClient: 24 POST /jobs/ HTTP/1.1
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [07/Jan/2012:12:30:50 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:50 +0100] cupsdReadClient: 24 1.1 Cancel-Job 1
D [07/Jan/2012:12:30:50 +0100] Cancel-Job ipp://localhost/jobs/11
D [07/Jan/2012:12:30:50 +0100] cupsdIsAuthorized: requesting-user-name="mezgrman"
D [07/Jan/2012:12:30:50 +0100] [Job 11] Loading attributes...
D [07/Jan/2012:12:30:50 +0100] cupsdMarkDirty(-----S)
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
I [07/Jan/2012:12:30:50 +0100] [Job 11] Job canceled by "mezgrman"
D [07/Jan/2012:12:30:50 +0100] cupsdMarkDirty(----J-)
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [07/Jan/2012:12:30:50 +0100] [Job 11] Canceled by "mezgrman".
D [07/Jan/2012:12:30:50 +0100] Returning IPP successful-ok for Cancel-Job (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:30:50 +0100] [Notifier] state=3
D [07/Jan/2012:12:30:50 +0100] [Notifier] JobCompleted
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:50 +0100] cupsdAcceptClient: 25 from localhost (Domain)
D [07/Jan/2012:12:30:50 +0100] cupsdReadClient: 25 POST / HTTP/1.1
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:50 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:50 +0100] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [07/Jan/2012:12:30:50 +0100] Get-Job-Attributes ipp://localhost/jobs/11
D [07/Jan/2012:12:30:50 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/11) from localhost
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:50 +0100] cupsdAcceptClient: 27 from localhost (Domain)
D [07/Jan/2012:12:30:50 +0100] cupsdReadClient: 27 POST / HTTP/1.1
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:50 +0100] cupsdAuthorize: No authentication data provided.
D [07/Jan/2012:12:30:50 +0100] cupsdReadClient: 27 1.1 Get-Notifications 1
D [07/Jan/2012:12:30:50 +0100] Get-Notifications /
D [07/Jan/2012:12:30:50 +0100] cupsdIsAuthorized: requesting-user-name="mezgrman"
D [07/Jan/2012:12:30:50 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [07/Jan/2012:12:30:50 +0100] cupsdReadClient: 27 WAITING Closing on EOF
D [07/Jan/2012:12:30:50 +0100] cupsdCloseClient: 27
D [07/Jan/2012:12:30:50 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [07/Jan/2012:12:30:51 +0100] cupsdReadClient: 24 WAITING Closing on EOF
D [07/Jan/2012:12:30:51 +0100] cupsdCloseClient: 24
D [07/Jan/2012:12:30:51 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [07/Jan/2012:12:31:21 +0100] Saving job.cache...
I [07/Jan/2012:12:31:21 +0100] Saving subscriptions.conf...
D [07/Jan/2012:12:31:22 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"