D [21/Feb/2012:20:07:02 +0100] [Job 135] kid3 finished D [21/Feb/2012:20:07:02 +0100] [Job 135] Read 3912 bytes of print data... D [21/Feb/2012:20:07:02 +0100] [Job 135] Kid3 exit status: 0 D [21/Feb/2012:20:07:02 +0100] [Job 135] D [21/Feb/2012:20:07:02 +0100] [Job 135] Closing foomatic-rip. D [21/Feb/2012:20:07:02 +0100] PID 6063 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [21/Feb/2012:20:07:02 +0100] [Job 135] Wrote 3912 bytes of print data... D [21/Feb/2012:20:07:02 +0100] [Job 135] Sent 3912 bytes... D [21/Feb/2012:20:07:02 +0100] [Job 135] Waiting for read thread to exit... D [21/Feb/2012:20:07:02 +0100] PID 6064 (/usr/lib/cups/backend/usb) exited with no errors. D [21/Feb/2012:20:07:02 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:07:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [21/Feb/2012:20:07:02 +0100] [Job 135] Job completed. D [21/Feb/2012:20:07:02 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:07:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:07:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:07:02 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:07:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:07:03 +0100] cupsdAcceptClient: 17 from localhost (Domain) I [21/Feb/2012:20:07:03 +0100] Saving job.cache... I [21/Feb/2012:20:07:03 +0100] Saving subscriptions.conf... D [21/Feb/2012:20:07:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files" D [21/Feb/2012:20:07:03 +0100] [Job 135] Unloading... D [21/Feb/2012:20:07:03 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:07:03 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:07:03 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:07:03 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:07:03 +0100] Get-Notifications / D [21/Feb/2012:20:07:03 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:07:03 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:07:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:07:03 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:07:03 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:07:03 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:07:03 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:07:03 +0100] cupsdReadClient: 21 1.1 Get-Printer-Attributes 1 D [21/Feb/2012:20:07:03 +0100] Get-Printer-Attributes ipp://ecosimplix:631/printers/DESKJET-710C D [21/Feb/2012:20:07:03 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://ecosimplix:631/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:07:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:07:03 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:07:03 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:07:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:07:22 +0100] Closing client 16 after 300 seconds of inactivity... D [21/Feb/2012:20:07:22 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:07:22 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:07:22 +0100] Report: clients=4 D [21/Feb/2012:20:07:22 +0100] Report: jobs=3 D [21/Feb/2012:20:07:22 +0100] Report: jobs-active=0 D [21/Feb/2012:20:07:22 +0100] Report: printers=1 D [21/Feb/2012:20:07:22 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:07:22 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:07:22 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:07:22 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:07:34 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:07:34 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:07:34 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:07:34 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:08:36 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:08:36 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:08:36 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:08:36 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:08:36 +0100] Report: clients=4 D [21/Feb/2012:20:08:36 +0100] Report: jobs=3 D [21/Feb/2012:20:08:36 +0100] Report: jobs-active=0 D [21/Feb/2012:20:08:36 +0100] Report: printers=1 D [21/Feb/2012:20:08:36 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:08:36 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:08:36 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:08:36 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:08:40 +0100] Closing client 20 after 300 seconds of inactivity... D [21/Feb/2012:20:08:40 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:08:40 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:08:42 +0100] Closing client 18 after 300 seconds of inactivity... D [21/Feb/2012:20:08:42 +0100] cupsdCloseClient: 18 D [21/Feb/2012:20:08:42 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:09:38 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:09:38 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:09:38 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:09:38 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:09:38 +0100] Report: clients=2 D [21/Feb/2012:20:09:38 +0100] Report: jobs=3 D [21/Feb/2012:20:09:38 +0100] Report: jobs-active=0 D [21/Feb/2012:20:09:38 +0100] Report: printers=1 D [21/Feb/2012:20:09:38 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:09:38 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:09:38 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:09:38 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:10:40 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:10:40 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:10:40 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:10:40 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:10:40 +0100] Report: clients=2 D [21/Feb/2012:20:10:40 +0100] Report: jobs=3 D [21/Feb/2012:20:10:40 +0100] Report: jobs-active=0 D [21/Feb/2012:20:10:40 +0100] Report: printers=1 D [21/Feb/2012:20:10:40 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:10:40 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:10:40 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:10:40 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:11:36 +0100] Closing client 19 after 300 seconds of inactivity... D [21/Feb/2012:20:11:36 +0100] cupsdCloseClient: 19 D [21/Feb/2012:20:11:36 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:11:43 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:11:43 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:11:43 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:11:43 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:11:43 +0100] Report: clients=1 D [21/Feb/2012:20:11:43 +0100] Report: jobs=3 D [21/Feb/2012:20:11:43 +0100] Report: jobs-active=0 D [21/Feb/2012:20:11:43 +0100] Report: printers=1 D [21/Feb/2012:20:11:43 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:11:43 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:11:43 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:11:43 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:12:04 +0100] Closing client 21 after 300 seconds of inactivity... D [21/Feb/2012:20:12:04 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:12:04 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:12:45 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:12:45 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:12:45 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:12:45 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:12:45 +0100] Report: clients=0 D [21/Feb/2012:20:12:45 +0100] Report: jobs=3 D [21/Feb/2012:20:12:45 +0100] Report: jobs-active=0 D [21/Feb/2012:20:12:45 +0100] Report: printers=1 D [21/Feb/2012:20:12:45 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:12:45 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:12:45 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:12:45 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:13:47 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:13:47 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:13:47 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:13:47 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:13:47 +0100] Report: clients=0 D [21/Feb/2012:20:13:47 +0100] Report: jobs=3 D [21/Feb/2012:20:13:47 +0100] Report: jobs-active=0 D [21/Feb/2012:20:13:47 +0100] Report: printers=1 D [21/Feb/2012:20:13:47 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:13:47 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:13:47 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:13:47 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:14:49 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:14:49 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:14:49 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:14:49 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:14:49 +0100] Report: clients=0 D [21/Feb/2012:20:14:49 +0100] Report: jobs=3 D [21/Feb/2012:20:14:49 +0100] Report: jobs-active=0 D [21/Feb/2012:20:14:49 +0100] Report: printers=1 D [21/Feb/2012:20:14:49 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:14:49 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:14:49 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:14:49 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:15:51 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:15:51 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:15:51 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:15:51 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:15:51 +0100] Report: clients=0 D [21/Feb/2012:20:15:51 +0100] Report: jobs=3 D [21/Feb/2012:20:15:51 +0100] Report: jobs-active=0 D [21/Feb/2012:20:15:51 +0100] Report: printers=1 D [21/Feb/2012:20:15:51 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:15:51 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:15:51 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:15:51 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:16:53 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:16:53 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:16:53 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:16:53 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:16:53 +0100] Report: clients=0 D [21/Feb/2012:20:16:53 +0100] Report: jobs=3 D [21/Feb/2012:20:16:53 +0100] Report: jobs-active=0 D [21/Feb/2012:20:16:53 +0100] Report: printers=1 D [21/Feb/2012:20:16:53 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:16:53 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:16:53 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:16:53 +0100] Report: stringpool-total-bytes=62648 D [21/Feb/2012:20:17:55 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:17:55 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:17:55 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:17:55 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:17:55 +0100] Report: clients=0 D [21/Feb/2012:20:17:55 +0100] Report: jobs=3 D [21/Feb/2012:20:17:55 +0100] Report: jobs-active=0 D [21/Feb/2012:20:17:55 +0100] Report: printers=1 D [21/Feb/2012:20:17:55 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:17:55 +0100] Report: stringpool-string-count=3012 D [21/Feb/2012:20:17:55 +0100] Report: stringpool-alloc-bytes=9400 D [21/Feb/2012:20:17:55 +0100] Report: stringpool-total-bytes=62648 I [21/Feb/2012:20:18:08 +0100] Scheduler shutting down normally. D [21/Feb/2012:20:18:08 +0100] Discarding unused server-stopped event... D [21/Feb/2012:20:18:08 +0100] cupsdDeregisterPrinter(p=0x221d8748(DESKJET-710C), removeit=1) I [21/Feb/2012:20:18:08 +0100] Saving job.cache... I [21/Feb/2012:20:18:56 +0100] Remote access is enabled. D [21/Feb/2012:20:18:56 +0100] Added auto ServerAlias ecosimplix I [21/Feb/2012:20:18:56 +0100] Loaded configuration file "/etc/cups/cupsd.conf" D [21/Feb/2012:20:18:56 +0100] Repairing ownership of "/var/run/cups" D [21/Feb/2012:20:18:56 +0100] Repairing ownership of "/var/run/cups/certs" D [21/Feb/2012:20:18:56 +0100] Repairing access permissions of "/var/run/cups/certs" I [21/Feb/2012:20:18:56 +0100] Using default TempDir of /var/spool/cups/tmp... I [21/Feb/2012:20:18:56 +0100] Configured for up to 100 clients. I [21/Feb/2012:20:18:56 +0100] Allowing up to 100 client connections per host. I [21/Feb/2012:20:18:56 +0100] Using policy "default" as the default. I [21/Feb/2012:20:18:56 +0100] Full reload is required. I [21/Feb/2012:20:18:56 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 52 filters... D [21/Feb/2012:20:18:56 +0100] Loading printer DESKJET-710C... D [21/Feb/2012:20:18:56 +0100] load_ppd: Loading /var/cache/cups/DESKJET-710C.data... D [21/Feb/2012:20:18:56 +0100] Calling DeleteDevice(cups-DESKJET-710C) D [21/Feb/2012:20:18:56 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)' D [21/Feb/2012:20:18:56 +0100] Using profile id of DESKJET-710C-Gray.. D [21/Feb/2012:20:18:56 +0100] Calling CreateProfile(DESKJET-710C-Gray..,temp) D [21/Feb/2012:20:18:59 +0100] created profile /org/freedesktop/ColorManager/profiles/DESKJET_710C_Gray__ D [21/Feb/2012:20:18:59 +0100] Using profile id of DESKJET-710C-RGB.. D [21/Feb/2012:20:18:59 +0100] Calling CreateProfile(DESKJET-710C-RGB..,temp) D [21/Feb/2012:20:18:59 +0100] created profile /org/freedesktop/ColorManager/profiles/DESKJET_710C_RGB__ I [21/Feb/2012:20:18:59 +0100] Registering ICC color profiles for "DESKJET-710C" D [21/Feb/2012:20:18:59 +0100] Calling CreateDevice(cups-DESKJET-710C,temp) D [21/Feb/2012:20:18:59 +0100] created device /org/freedesktop/ColorManager/devices/cups_DESKJET_710C D [21/Feb/2012:20:18:59 +0100] Calling /org/freedesktop/ColorManager/devices/cups_DESKJET_710C:AddProfile(/org/freedesktop/ColorManager/profiles/DESKJET_710C_Gray__) [soft] D [21/Feb/2012:20:18:59 +0100] Calling /org/freedesktop/ColorManager/devices/cups_DESKJET_710C:AddProfile(/org/freedesktop/ColorManager/profiles/DESKJET_710C_RGB__) [soft] D [21/Feb/2012:20:18:59 +0100] cupsdRegisterPrinter(p=0x22cde748(DESKJET-710C)) I [21/Feb/2012:20:18:59 +0100] Generating printcap /var/run/cups/printcap... I [21/Feb/2012:20:18:59 +0100] Loading job cache file "/var/cache/cups/job.cache"... D [21/Feb/2012:20:18:59 +0100] [Job 133] Loading from cache... D [21/Feb/2012:20:18:59 +0100] [Job 134] Loading from cache... D [21/Feb/2012:20:18:59 +0100] [Job 135] Loading from cache... D [21/Feb/2012:20:18:59 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") D [21/Feb/2012:20:18:59 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") D [21/Feb/2012:20:18:59 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") D [21/Feb/2012:20:18:59 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") I [21/Feb/2012:20:18:59 +0100] Full reload complete. D [21/Feb/2012:20:18:59 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)") I [21/Feb/2012:20:18:59 +0100] Cleaning out old files in "/var/spool/cups/tmp"... D [21/Feb/2012:20:18:59 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp") I [21/Feb/2012:20:18:59 +0100] Cleaning out old files in "/var/cache/cups"... I [21/Feb/2012:20:18:59 +0100] Listening to 0.0.0.0:631 on fd 9... I [21/Feb/2012:20:18:59 +0100] Listening to [v1.::]:631 on fd 10... I [21/Feb/2012:20:18:59 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11... I [21/Feb/2012:20:18:59 +0100] Resuming new connection processing... D [21/Feb/2012:20:18:59 +0100] Avahi client started D [21/Feb/2012:20:18:59 +0100] Registering Avahi printer DESKJET-710C with name "HEWLETT-PACKARD DESKJET 710C @ ecosimplix" and type "_ipp._tcp,_cups,_universal" D [21/Feb/2012:20:18:59 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_ipp._tcp) D [21/Feb/2012:20:18:59 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_cups._sub._ipp._tcp) D [21/Feb/2012:20:18:59 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_universal._sub._ipp._tcp) D [21/Feb/2012:20:18:59 +0100] cupsdRegisterPrinter(p=0x22cde748(DESKJET-710C)) D [21/Feb/2012:20:18:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:18:59 +0100] Discarding unused server-started event... D [21/Feb/2012:20:18:59 +0100] Report: clients=0 D [21/Feb/2012:20:18:59 +0100] Report: jobs=3 D [21/Feb/2012:20:18:59 +0100] Report: jobs-active=0 D [21/Feb/2012:20:18:59 +0100] Report: printers=1 D [21/Feb/2012:20:18:59 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:18:59 +0100] Report: stringpool-string-count=574 D [21/Feb/2012:20:18:59 +0100] Report: stringpool-alloc-bytes=8240 D [21/Feb/2012:20:18:59 +0100] Report: stringpool-total-bytes=11072 D [21/Feb/2012:20:19:00 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:19:00 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:19:00 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:19:00 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:19:00 +0100] Avahi entry group established for HEWLETT-PACKARD DESKJET 710C @ ecosimplix D [21/Feb/2012:20:19:02 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:19:02 +0100] cupsdReadClient: 16 POST / HTTP/1.1 D [21/Feb/2012:20:19:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:19:02 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:19:02 +0100] cupsdReadClient: 16 1.1 Get-Jobs 1 D [21/Feb/2012:20:19:02 +0100] Get-Jobs ipp://localhost/printers/ D [21/Feb/2012:20:19:02 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost D [21/Feb/2012:20:19:02 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:19:02 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:19:02 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:19:02 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:20:02 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:20:02 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:20:02 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:20:02 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:20:02 +0100] Report: clients=0 D [21/Feb/2012:20:20:02 +0100] Report: jobs=3 D [21/Feb/2012:20:20:02 +0100] Report: jobs-active=0 D [21/Feb/2012:20:20:02 +0100] Report: printers=1 D [21/Feb/2012:20:20:02 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:20:02 +0100] Report: stringpool-string-count=574 D [21/Feb/2012:20:20:02 +0100] Report: stringpool-alloc-bytes=8240 D [21/Feb/2012:20:20:02 +0100] Report: stringpool-total-bytes=11072 D [21/Feb/2012:20:21:04 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:21:04 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:21:04 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:21:04 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:21:04 +0100] Report: clients=0 D [21/Feb/2012:20:21:04 +0100] Report: jobs=3 D [21/Feb/2012:20:21:04 +0100] Report: jobs-active=0 D [21/Feb/2012:20:21:04 +0100] Report: printers=1 D [21/Feb/2012:20:21:04 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:21:04 +0100] Report: stringpool-string-count=574 D [21/Feb/2012:20:21:04 +0100] Report: stringpool-alloc-bytes=8240 D [21/Feb/2012:20:21:04 +0100] Report: stringpool-total-bytes=11072 D [21/Feb/2012:20:22:06 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:22:06 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:22:06 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:22:06 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:22:06 +0100] Report: clients=0 D [21/Feb/2012:20:22:06 +0100] Report: jobs=3 D [21/Feb/2012:20:22:06 +0100] Report: jobs-active=0 D [21/Feb/2012:20:22:06 +0100] Report: printers=1 D [21/Feb/2012:20:22:06 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:22:06 +0100] Report: stringpool-string-count=574 D [21/Feb/2012:20:22:06 +0100] Report: stringpool-alloc-bytes=8240 D [21/Feb/2012:20:22:06 +0100] Report: stringpool-total-bytes=11072 D [21/Feb/2012:20:22:24 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:24 +0100] cupsdReadClient: 16 POST /admin/ HTTP/1.1 D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:22:24 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:24 +0100] cupsdReadClient: 16 1.1 Purge-Jobs 1 D [21/Feb/2012:20:22:24 +0100] Purge-Jobs ipp://localhost/printers/ D [21/Feb/2012:20:22:24 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:22:24 +0100] [Job 133] Loading attributes... D [21/Feb/2012:20:22:24 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" I [21/Feb/2012:20:22:24 +0100] [Job 133] Job purged by user. D [21/Feb/2012:20:22:24 +0100] [Job 133] Unloading... D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:24 +0100] [Job 134] Loading attributes... D [21/Feb/2012:20:22:24 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:22:24 +0100] [Job 134] Job purged by user. D [21/Feb/2012:20:22:24 +0100] [Job 134] Unloading... D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:24 +0100] [Job 135] Loading attributes... D [21/Feb/2012:20:22:24 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:22:24 +0100] [Job 135] Job purged by user. D [21/Feb/2012:20:22:24 +0100] [Job 135] Unloading... D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:22:24 +0100] All jobs were purged by "pavel". D [21/Feb/2012:20:22:24 +0100] Returning IPP successful-ok for Purge-Jobs (ipp://localhost/printers/) from localhost D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:24 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:24 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:24 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 16 POST /admin/ HTTP/1.1 D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 16 1.1 Pause-Printer 1 D [21/Feb/2012:20:22:35 +0100] Pause-Printer ipp://localhost/printers/DESKJET-710C D [21/Feb/2012:20:22:35 +0100] cupsdIsAuthorized: username="" D [21/Feb/2012:20:22:35 +0100] Returning HTTP Unauthorized for Pause-Printer (ipp://localhost/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:22:35 +0100] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS", trc="y" D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:35 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:35 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:35 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 17 POST /admin/ HTTP/1.1 D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdAuthorize: Authorized as pavel using PeerCred D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 17 1.1 Pause-Printer 1 D [21/Feb/2012:20:22:35 +0100] Pause-Printer ipp://localhost/printers/DESKJET-710C D [21/Feb/2012:20:22:35 +0100] cupsdIsAuthorized: username="pavel" D [21/Feb/2012:20:22:35 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdMarkDirty(P-----) D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdRegisterPrinter(p=0x22cde748(DESKJET-710C)) D [21/Feb/2012:20:22:35 +0100] Updating TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_ipp._tcp) E [21/Feb/2012:20:22:35 +0100] Failed to update TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix: -2 D [21/Feb/2012:20:22:35 +0100] Registering Avahi printer DESKJET-710C with name "HEWLETT-PACKARD DESKJET 710C @ ecosimplix" and type "_ipp._tcp,_cups,_universal" D [21/Feb/2012:20:22:35 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_ipp._tcp) D [21/Feb/2012:20:22:35 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_cups._sub._ipp._tcp) D [21/Feb/2012:20:22:35 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_universal._sub._ipp._tcp) D [21/Feb/2012:20:22:35 +0100] cupsdMarkDirty(P-----) D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:22:35 +0100] Printer "DESKJET-710C" stopped by "pavel". D [21/Feb/2012:20:22:35 +0100] Returning IPP successful-ok for Pause-Printer (ipp://localhost/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:35 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 16 POST / HTTP/1.1 D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 16 1.1 Get-Jobs 1 D [21/Feb/2012:20:22:35 +0100] Get-Jobs ipp://localhost/printers/ D [21/Feb/2012:20:22:35 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:35 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:35 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:35 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:36 +0100] Avahi entry group established for HEWLETT-PACKARD DESKJET 710C @ ecosimplix D [21/Feb/2012:20:22:42 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:42 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:42 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:42 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:42 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 POST / HTTP/1.1 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 GET /printers/DESKJET-710C.ppd HTTP/1.1 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 POST / HTTP/1.1 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:44 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:44 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:44 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:44 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:44 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:44 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:44 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:44 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:44 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:44 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:45 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:45 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:45 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:45 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:45 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:45 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:45 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:45 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:45 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:45 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:45 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:45 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:46 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:46 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:46 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:46 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:46 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:46 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:46 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:46 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:46 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:46 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:47 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:47 +0100] cupsdReadClient: 16 POST /printers/DESKJET-710C HTTP/1.1 D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:47 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:47 +0100] cupsdReadClient: 16 1.1 Print-Job 1 D [21/Feb/2012:20:22:47 +0100] Print-Job ipp://localhost:631/printers/DESKJET-710C D [21/Feb/2012:20:22:47 +0100] [Job ???] Auto-typing file... I [21/Feb/2012:20:22:47 +0100] [Job ???] Request file type is application/pdf. D [21/Feb/2012:20:22:47 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:47 +0100] add_job: requesting-user-name="pavel" I [21/Feb/2012:20:22:47 +0100] [Job 136] Adding start banner page "none". D [21/Feb/2012:20:22:47 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:47 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:22:47 +0100] [Job 136] Adding end banner page "none". I [21/Feb/2012:20:22:47 +0100] [Job 136] File of type application/pdf queued by "pavel". D [21/Feb/2012:20:22:47 +0100] [Job 136] hold_until=0 I [21/Feb/2012:20:22:47 +0100] [Job 136] Queued on "DESKJET-710C" by "pavel". D [21/Feb/2012:20:22:47 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:47 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:47 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:47 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:47 +0100] cupsdReadClient: 16 POST / HTTP/1.1 D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:47 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:47 +0100] cupsdReadClient: 16 1.1 Get-Jobs 1 D [21/Feb/2012:20:22:47 +0100] Get-Jobs ipp://localhost/printers/ D [21/Feb/2012:20:22:47 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:47 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [21/Feb/2012:20:22:47 +0100] cupsdCloseClient: 16 D [21/Feb/2012:20:22:47 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:48 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [21/Feb/2012:20:22:49 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 1.1 Create-Printer-Subscription 1 D [21/Feb/2012:20:22:49 +0100] Create-Printer-Subscription / D [21/Feb/2012:20:22:49 +0100] cupsdCreateSubscription(con=0x22d169a8(17), uri="/") D [21/Feb/2012:20:22:49 +0100] pullmethod="ippget" D [21/Feb/2012:20:22:49 +0100] notify-lease-duration=86400 D [21/Feb/2012:20:22:49 +0100] notify-time-interval=0 D [21/Feb/2012:20:22:49 +0100] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)") D [21/Feb/2012:20:22:49 +0100] Added subscription #164 for server. D [21/Feb/2012:20:22:49 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:49 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:49 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:22:49 +0100] CUPS-Get-Printers D [21/Feb/2012:20:22:49 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAcceptClient: 18 from localhost (Domain) D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1 D [21/Feb/2012:20:22:49 +0100] Get-Printer-Attributes ipp://localhost/printers/DESKJET-710C D [21/Feb/2012:20:22:49 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 18 WAITING Closing on EOF D [21/Feb/2012:20:22:49 +0100] cupsdCloseClient: 18 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:49 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 1.1 Get-Jobs 1 D [21/Feb/2012:20:22:49 +0100] Get-Jobs ipp://localhost/printers/ D [21/Feb/2012:20:22:49 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAcceptClient: 18 from localhost (Domain) D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 18 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:22:49 +0100] Get-Job-Attributes ipp://localhost/jobs/136 D [21/Feb/2012:20:22:49 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/136) from localhost D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 18 WAITING Closing on EOF D [21/Feb/2012:20:22:49 +0100] cupsdCloseClient: 18 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:49 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 1.1 Get-Jobs 1 D [21/Feb/2012:20:22:49 +0100] Get-Jobs ipp://localhost/printers/ D [21/Feb/2012:20:22:49 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:49 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:49 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:22:50 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:50 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:50 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:22:50 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:50 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:22:50 +0100] Get-Notifications / D [21/Feb/2012:20:22:50 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:22:50 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:22:50 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:22:50 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:50 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:50 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [21/Feb/2012:20:22:55 +0100] Saving printers.conf... I [21/Feb/2012:20:22:55 +0100] Saving job.cache... I [21/Feb/2012:20:22:56 +0100] Saving subscriptions.conf... D [21/Feb/2012:20:22:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [21/Feb/2012:20:22:57 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:22:57 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:22:57 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:22:57 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:22:57 +0100] cupsdReadClient: 17 1.1 Get-Jobs 1 D [21/Feb/2012:20:22:57 +0100] Get-Jobs ipp://localhost/ D [21/Feb/2012:20:22:57 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost D [21/Feb/2012:20:22:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:22:57 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:22:57 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:22:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:23:07 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:23:07 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:23:07 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:23:07 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:23:07 +0100] Report: clients=1 D [21/Feb/2012:20:23:07 +0100] Report: jobs=1 D [21/Feb/2012:20:23:07 +0100] Report: jobs-active=1 D [21/Feb/2012:20:23:07 +0100] Report: printers=1 D [21/Feb/2012:20:23:07 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:23:07 +0100] Report: stringpool-string-count=1030 D [21/Feb/2012:20:23:07 +0100] Report: stringpool-alloc-bytes=10112 D [21/Feb/2012:20:23:07 +0100] Report: stringpool-total-bytes=20032 D [21/Feb/2012:20:23:50 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:23:50 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:23:50 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:23:50 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:23:50 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:23:50 +0100] Get-Notifications / D [21/Feb/2012:20:23:50 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:23:50 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:23:50 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:23:50 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:23:50 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:23:50 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:09 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:24:09 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:24:09 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:24:09 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:24:09 +0100] Report: clients=1 D [21/Feb/2012:20:24:09 +0100] Report: jobs=1 D [21/Feb/2012:20:24:09 +0100] Report: jobs-active=1 D [21/Feb/2012:20:24:09 +0100] Report: printers=1 D [21/Feb/2012:20:24:09 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:24:09 +0100] Report: stringpool-string-count=1030 D [21/Feb/2012:20:24:09 +0100] Report: stringpool-alloc-bytes=10112 D [21/Feb/2012:20:24:09 +0100] Report: stringpool-total-bytes=20032 D [21/Feb/2012:20:24:10 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:10 +0100] cupsdReadClient: 17 POST /admin/ HTTP/1.1 D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:10 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:10 +0100] cupsdReadClient: 17 1.1 Resume-Printer 1 D [21/Feb/2012:20:24:10 +0100] Resume-Printer ipp://localhost/printers/DESKJET-710C D [21/Feb/2012:20:24:10 +0100] cupsdIsAuthorized: username="" D [21/Feb/2012:20:24:10 +0100] Returning HTTP Unauthorized for Resume-Printer (ipp://localhost/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:24:10 +0100] cupsdSendHeader: 17 WWW-Authenticate: Basic realm="CUPS", trc="y" D [21/Feb/2012:20:24:10 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:10 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:10 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:10 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:10 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:10 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:10 +0100] cupsdReadClient: 17 POST /admin/ HTTP/1.1 D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:10 +0100] cupsdAuthorize: Authorized as pavel using PeerCred D [21/Feb/2012:20:24:10 +0100] cupsdReadClient: 17 1.1 Resume-Printer 1 D [21/Feb/2012:20:24:10 +0100] Resume-Printer ipp://localhost/printers/DESKJET-710C D [21/Feb/2012:20:24:10 +0100] cupsdIsAuthorized: username="pavel" D [21/Feb/2012:20:24:10 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [21/Feb/2012:20:24:10 +0100] cupsdMarkDirty(P-----) D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:10 +0100] cupsdRegisterPrinter(p=0x22cde748(DESKJET-710C)) D [21/Feb/2012:20:24:10 +0100] Updating TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_ipp._tcp) E [21/Feb/2012:20:24:10 +0100] Failed to update TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix: -2 D [21/Feb/2012:20:24:10 +0100] Registering Avahi printer DESKJET-710C with name "HEWLETT-PACKARD DESKJET 710C @ ecosimplix" and type "_ipp._tcp,_cups,_universal" D [21/Feb/2012:20:24:10 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_ipp._tcp) D [21/Feb/2012:20:24:10 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_cups._sub._ipp._tcp) D [21/Feb/2012:20:24:10 +0100] Adding TXT record for HEWLETT-PACKARD DESKJET 710C @ ecosimplix (_universal._sub._ipp._tcp) D [21/Feb/2012:20:24:10 +0100] cupsdMarkDirty(P-----) D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:24:10 +0100] Printer "DESKJET-710C" started by "pavel". D [21/Feb/2012:20:24:10 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:10 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:10 +0100] [Job 136] job-sheets=none,none D [21/Feb/2012:20:24:10 +0100] [Job 136] argv[0]="DESKJET-710C" D [21/Feb/2012:20:24:10 +0100] [Job 136] argv[1]="136" D [21/Feb/2012:20:24:10 +0100] [Job 136] argv[2]="pavel" D [21/Feb/2012:20:24:10 +0100] [Job 136] argv[3]="Leafpad: úloha č. 1" D [21/Feb/2012:20:24:10 +0100] [Job 136] argv[4]="1" D [21/Feb/2012:20:24:10 +0100] [Job 136] argv[5]="number-up=1 ColorMode=Grayscale XOffset=150 Dither=FloydSteinberg EconoFast=On LeftMargin=10 YOffset=50 PageSize=A4 GammaFile=Default pnmFormat=PixMap TopMargin=10 Bidirectional=Default RightMargin=10 BottomMargin=150 Blackness=2 job-uuid=urn:uuid:9dc53ea7-126a-3b91-4669-3be31114dcef print-color-mode=monochrome job-originating-host-name=localhost time-at-creation=1329852167 time-at-processing=1329852250 AP_D_InputSlot=" D [21/Feb/2012:20:24:10 +0100] [Job 136] argv[6]="/var/spool/cups/d00136-001" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[1]="CUPS_DATADIR=/usr/share/cups" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[6]="CUPS_SERVERROOT=/etc/cups" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[7]="CUPS_STATEDIR=/var/run/cups" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[8]="HOME=/var/spool/cups/tmp" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[10]="SERVER_ADMIN=root@ecosimplix" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[11]="SOFTWARE=CUPS/1.5.2" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[12]="TMPDIR=/var/spool/cups/tmp" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[13]="USER=root" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[15]="CUPS_ENCRYPTION=IfRequested" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[16]="IPP_PORT=631" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[17]="CHARSET=utf-8" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[18]="LANG=cs_CZ.UTF-8" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[19]="PPD=/etc/cups/ppd/DESKJET-710C.ppd" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[20]="RIP_MAX_CACHE=128m" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[21]="CONTENT_TYPE=application/pdf" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[22]="DEVICE_URI=usb://HP/DESKJET%20710C" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[23]="PRINTER_INFO=HEWLETT-PACKARD DESKJET 710C" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[24]="PRINTER_LOCATION=ecosimplix" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[25]="PRINTER=DESKJET-710C" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[26]="PRINTER_STATE_REASONS=none" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[27]="CUPS_FILETYPE=document" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[28]="FINAL_CONTENT_TYPE=printer/DESKJET-710C" D [21/Feb/2012:20:24:10 +0100] [Job 136] envp[29]="AUTH_I****" I [21/Feb/2012:20:24:10 +0100] [Job 136] Started filter /usr/lib/cups/filter/pdftopdf (PID 1617) I [21/Feb/2012:20:24:10 +0100] [Job 136] Started filter /usr/lib/cups/filter/foomatic-rip (PID 1618) I [21/Feb/2012:20:24:10 +0100] [Job 136] Started backend /usr/lib/cups/backend/usb (PID 1619) D [21/Feb/2012:20:24:10 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:10 +0100] Returning IPP successful-ok for Resume-Printer (ipp://localhost/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:10 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:10 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] [Job 136] Getting input from file D [21/Feb/2012:20:24:11 +0100] [Job 136] foomatic-rip version 4.0.12.246 running... D [21/Feb/2012:20:24:11 +0100] [Job 136] Parsing PPD file ... D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option ColorSpace D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option Resolution D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option PageSize D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option ImageableArea D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option PaperDimension D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option ColorMode D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option EconoFast D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option Dither D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option Bidirectional D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option Blackness D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option TopMargin D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option BottomMargin D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option LeftMargin D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option RightMargin D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option XOffset D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option YOffset D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option Model D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option pnmFormat D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option GammaFile D [21/Feb/2012:20:24:11 +0100] [Job 136] Added option Font D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] [Job 136] Parameter Summary D [21/Feb/2012:20:24:11 +0100] [Job 136] ----------------- D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] [Job 136] Spooler: cups D [21/Feb/2012:20:24:11 +0100] [Job 136] Printer: DESKJET-710C D [21/Feb/2012:20:24:11 +0100] [Job 136] Shell: /bin/bash D [21/Feb/2012:20:24:11 +0100] [Job 136] PPD file: /etc/cups/ppd/DESKJET-710C.ppd D [21/Feb/2012:20:24:11 +0100] [Job 136] ATTR file: D [21/Feb/2012:20:24:11 +0100] [Job 136] Printer model: HP DeskJet 710C Foomatic/pnm2ppa (recommended) D [21/Feb/2012:20:24:11 +0100] [Job 136] Job title: Leafpad: úloha č. 1 D [21/Feb/2012:20:24:11 +0100] [Job 136] File(s) to be printed: D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] [Job 136] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [21/Feb/2012:20:24:11 +0100] [Job 136] Printing system options: D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'number-up=1' D [21/Feb/2012:20:24:11 +0100] [Job 136] Unknown option number-up=1. D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'job-uuid=urn:uuid:9dc53ea7-126a-3b91-4669-3be31114dcef' D [21/Feb/2012:20:24:11 +0100] [Job 136] Unknown option job-uuid=urn:uuid:9dc53ea7-126a-3b91-4669-3be31114dcef. D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'print-color-mode=monochrome' D [21/Feb/2012:20:24:11 +0100] [Job 136] Unknown option print-color-mode=monochrome. D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'job-originating-host-name=localhost' D [21/Feb/2012:20:24:11 +0100] [Job 136] Unknown option job-originating-host-name=localhost. D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'time-at-creation=1329852167' D [21/Feb/2012:20:24:11 +0100] [Job 136] Unknown option time-at-creation=1329852167. D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'time-at-processing=1329852250' D [21/Feb/2012:20:24:11 +0100] [Job 136] Unknown option time-at-processing=1329852250. D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'AP_D_InputSlot=' D [21/Feb/2012:20:24:11 +0100] [Job 136] Unknown option AP_D_InputSlot=. D [21/Feb/2012:20:24:11 +0100] [Job 136] Options from the PPD file: D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'ColorMode=Grayscale' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'XOffset=150' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'Dither=FloydSteinberg' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'EconoFast=On' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'LeftMargin=10' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'YOffset=50' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'PageSize=A4' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'GammaFile=Default' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'pnmFormat=PixMap' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'TopMargin=10' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'Bidirectional=Default' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'RightMargin=10' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'BottomMargin=150' D [21/Feb/2012:20:24:11 +0100] [Job 136] Pondering option 'Blackness=2' D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] [Job 136] ================================================ D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] [Job 136] File: D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] [Job 136] ================================================ D [21/Feb/2012:20:24:11 +0100] [Job 136] D [21/Feb/2012:20:24:11 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:24:11 +0100] Get-Notifications / D [21/Feb/2012:20:24:11 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:24:11 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdAcceptClient: 19 from localhost (Domain) D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 19 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:24:11 +0100] Get-Job-Attributes ipp://localhost/jobs/136 D [21/Feb/2012:20:24:11 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/136) from localhost D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 19 WAITING Closing on EOF D [21/Feb/2012:20:24:11 +0100] cupsdCloseClient: 19 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:11 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] [Job 136] print_device D [21/Feb/2012:20:24:11 +0100] [Job 136] libusb_get_device_list=12 D [21/Feb/2012:20:24:11 +0100] [Job 136] Filetype: PDF D [21/Feb/2012:20:24:11 +0100] [Job 136] Storing temporary files in /var/spool/cups/tmp D [21/Feb/2012:20:24:11 +0100] PID 1617 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [21/Feb/2012:20:24:11 +0100] [Job 136] Error opening file for reading: Permission denied D [21/Feb/2012:20:24:11 +0100] [Job 136] STATE: +connecting-to-device D [21/Feb/2012:20:24:11 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] [Job 136] STATE: -connecting-to-device D [21/Feb/2012:20:24:11 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" I [21/Feb/2012:20:24:11 +0100] [Job 136] Sending data to printer. D [21/Feb/2012:20:24:11 +0100] [Job 136] Set job-printer-state-message to "Sending data to printer.", current level=INFO D [21/Feb/2012:20:24:11 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] [Job 136] Read 40 bytes of back-channel data... D [21/Feb/2012:20:24:11 +0100] [Job 136] Read 16 bytes of back-channel data... D [21/Feb/2012:20:24:11 +0100] Avahi entry group established for HEWLETT-PACKARD DESKJET 710C @ ecosimplix D [21/Feb/2012:20:24:11 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:24:11 +0100] Get-Notifications / D [21/Feb/2012:20:24:11 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:24:11 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdAcceptClient: 19 from localhost (Domain) D [21/Feb/2012:20:24:11 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 20 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:24:11 +0100] Get-Job-Attributes ipp://localhost/jobs/136 D [21/Feb/2012:20:24:11 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/136) from localhost D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:11 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:11 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:11 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:12 +0100] [Job 136] File contains 1 pages D [21/Feb/2012:20:24:12 +0100] [Job 136] Starting renderer with command: gs -dFirstPage=1 -q -dNOPAUSE -dNOINTERPOLATE -dPARANOIDSAFER -dBATCH -r600 -sDEVICE=ppmraw -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=- /var/spool/cups/tmp/foomatic-nfRYqo | pnm2ppa -v 710 --bw --eco -B 2 -t 10 -b 150 -l 10 -r 10 -x 150 -y 50 -i - -o - D [21/Feb/2012:20:24:12 +0100] [Job 136] Starting process "kid3" (generation 1) D [21/Feb/2012:20:24:12 +0100] [Job 136] Starting process "kid4" (generation 2) D [21/Feb/2012:20:24:12 +0100] [Job 136] JCL: %-12345X@PJL D [21/Feb/2012:20:24:12 +0100] [Job 136] Starting process "renderer" (generation 2) D [21/Feb/2012:20:24:12 +0100] [Job 136] D [21/Feb/2012:20:24:12 +0100] [Job 136] D [21/Feb/2012:20:24:12 +0100] [Job 136] Error opening file for reading: Permission denied D [21/Feb/2012:20:24:13 +0100] [Job 136] renderer exited with status 0 D [21/Feb/2012:20:24:13 +0100] [Job 136] kid4 exited with status 0 D [21/Feb/2012:20:24:13 +0100] [Job 136] kid3 finished D [21/Feb/2012:20:24:13 +0100] [Job 136] Read 3166 bytes of print data... D [21/Feb/2012:20:24:13 +0100] [Job 136] Kid3 exit status: 0 D [21/Feb/2012:20:24:13 +0100] [Job 136] D [21/Feb/2012:20:24:13 +0100] [Job 136] Closing foomatic-rip. D [21/Feb/2012:20:24:13 +0100] PID 1618 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [21/Feb/2012:20:24:13 +0100] [Job 136] Wrote 3166 bytes of print data... D [21/Feb/2012:20:24:13 +0100] [Job 136] Sent 3166 bytes... D [21/Feb/2012:20:24:13 +0100] [Job 136] Waiting for read thread to exit... D [21/Feb/2012:20:24:13 +0100] PID 1619 (/usr/lib/cups/backend/usb) exited with no errors. D [21/Feb/2012:20:24:13 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [21/Feb/2012:20:24:13 +0100] [Job 136] Job completed. D [21/Feb/2012:20:24:13 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:13 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:13 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:13 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:13 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:13 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:24:13 +0100] Get-Notifications / D [21/Feb/2012:20:24:13 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:24:13 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:13 +0100] cupsdAcceptClient: 18 from localhost (Domain) D [21/Feb/2012:20:24:13 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:24:13 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:13 +0100] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1 D [21/Feb/2012:20:24:13 +0100] Get-Printer-Attributes ipp://ecosimplix:631/printers/DESKJET-710C D [21/Feb/2012:20:24:13 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://ecosimplix:631/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:13 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:13 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:24:14 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:14 +0100] [Job 136] Unloading... D [21/Feb/2012:20:24:14 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:24:14 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:14 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Devices 1 D [21/Feb/2012:20:24:14 +0100] CUPS-Get-Devices D [21/Feb/2012:20:24:14 +0100] cupsdIsAuthorized: username="" D [21/Feb/2012:20:24:14 +0100] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost D [21/Feb/2012:20:24:14 +0100] cupsdSendHeader: 17 WWW-Authenticate: Basic realm="CUPS", trc="y" D [21/Feb/2012:20:24:14 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:14 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:14 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:14 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:14 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:14 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:24:14 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:24:14 +0100] cupsdAuthorize: Authorized as root using PeerCred D [21/Feb/2012:20:24:14 +0100] cupsdReadClient: 20 1.1 CUPS-Get-Devices 1 D [21/Feb/2012:20:24:14 +0100] CUPS-Get-Devices D [21/Feb/2012:20:24:14 +0100] cupsdIsAuthorized: username="root" D [21/Feb/2012:20:24:14 +0100] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced" D [21/Feb/2012:20:24:14 +0100] [CGI] argv[1] = "1" D [21/Feb/2012:20:24:14 +0100] [CGI] argv[2] = "0" D [21/Feb/2012:20:24:14 +0100] [CGI] argv[3] = "2" D [21/Feb/2012:20:24:14 +0100] [CGI] argv[4] = "7" D [21/Feb/2012:20:24:14 +0100] [CGI] argv[5] = "requested-attributes=all exclude-schemes='beh','cups-pdf','bluetooth','dnssd','http','https','ipp','lpd','ncp','parallel','scsi','smb','snmp','socket'" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@ecosimplix" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.2" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[13] = "USER=root" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[16] = "IPP_PORT=631" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[17] = "AUTH_TYPE=PeerCred" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[18] = "LANG=en_US.UTF8" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[23] = "REMOTE_ADDR=localhost" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[25] = "SCRIPT_NAME=/" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[27] = "REMOTE_USER=root" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/1.5.2" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[30] = "REQUEST_METHOD=POST" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[31] = "CONTENT_LENGTH=244" D [21/Feb/2012:20:24:14 +0100] [CGI] envp[32] = "CONTENT_TYPE=application/ipp" D [21/Feb/2012:20:24:14 +0100] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 1637) I [21/Feb/2012:20:24:14 +0100] Started "/usr/lib/cups/daemon/cups-deviced" (pid=1637) D [21/Feb/2012:20:24:14 +0100] cupsdSendCommand: 20 file=17 D [21/Feb/2012:20:24:14 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/ipps (PID 1638) D [21/Feb/2012:20:24:14 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/mdns (PID 1639) D [21/Feb/2012:20:24:14 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 1640) D [21/Feb/2012:20:24:14 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 1641) D [21/Feb/2012:20:24:14 +0100] [CGI] Flushed attributes... D [21/Feb/2012:20:24:14 +0100] [cups-deviced] Found device "ipps"... D [21/Feb/2012:20:24:14 +0100] Script header: Content-Type: application/ipp D [21/Feb/2012:20:24:14 +0100] Script header: D [21/Feb/2012:20:24:14 +0100] [CGI] list_devices D [21/Feb/2012:20:24:14 +0100] [cups-deviced] PID 1638 (ipps) exited with no errors. D [21/Feb/2012:20:24:14 +0100] [cups-deviced] PID 1639 (mdns) exited with no errors. D [21/Feb/2012:20:24:14 +0100] [CGI] libusb_get_device_list=12 D [21/Feb/2012:20:24:14 +0100] [cups-deviced] PID 1641 (serial) exited with no errors. D [21/Feb/2012:20:24:15 +0100] [CGI] Flushed attributes... D [21/Feb/2012:20:24:15 +0100] [cups-deviced] Found device "usb://HP/DESKJET%20710C"... D [21/Feb/2012:20:24:15 +0100] [cups-deviced] PID 1640 (usb) exited with no errors. D [21/Feb/2012:20:24:15 +0100] PID 1637 (/usr/lib/cups/daemon/cups-deviced) exited with no errors. D [21/Feb/2012:20:24:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:15 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:15 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:24:15 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:15 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:15 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:24:15 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:15 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:24:15 +0100] CUPS-Get-Printers D [21/Feb/2012:20:24:15 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:24:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:15 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:15 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [21/Feb/2012:20:24:41 +0100] Saving printers.conf... I [21/Feb/2012:20:24:41 +0100] Saving job.cache... I [21/Feb/2012:20:24:41 +0100] Saving subscriptions.conf... D [21/Feb/2012:20:24:41 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:24:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:24:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 17 GET /printers/DESKJET-710C.ppd HTTP/1.1 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:24:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:24:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:24:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:24:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:24:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:24:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:43 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:24:43 +0100] CUPS-Get-Printers D [21/Feb/2012:20:24:43 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:43 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:43 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:24:44 +0100] CUPS-Get-Printers D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 20 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 20 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 POST /printers/DESKJET-710C HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 1.1 Print-Job 1 D [21/Feb/2012:20:24:44 +0100] Print-Job ipp://localhost:631/printers/DESKJET-710C D [21/Feb/2012:20:24:44 +0100] [Job ???] Auto-typing file... I [21/Feb/2012:20:24:44 +0100] [Job ???] Request file type is application/pdf. D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [21/Feb/2012:20:24:44 +0100] add_job: requesting-user-name="pavel" I [21/Feb/2012:20:24:44 +0100] [Job 137] Adding start banner page "none". D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:24:44 +0100] [Job 137] Adding end banner page "none". I [21/Feb/2012:20:24:44 +0100] [Job 137] File of type application/pdf queued by "pavel". D [21/Feb/2012:20:24:44 +0100] [Job 137] hold_until=0 I [21/Feb/2012:20:24:44 +0100] [Job 137] Queued on "DESKJET-710C" by "pavel". D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] job-sheets=none,none D [21/Feb/2012:20:24:44 +0100] [Job 137] argv[0]="DESKJET-710C" D [21/Feb/2012:20:24:44 +0100] [Job 137] argv[1]="137" D [21/Feb/2012:20:24:44 +0100] [Job 137] argv[2]="pavel" D [21/Feb/2012:20:24:44 +0100] [Job 137] argv[3]="Leafpad: úloha č. 2" D [21/Feb/2012:20:24:44 +0100] [Job 137] argv[4]="1" D [21/Feb/2012:20:24:44 +0100] [Job 137] argv[5]="number-up=1 ColorMode=Grayscale XOffset=150 Dither=FloydSteinberg EconoFast=On LeftMargin=10 YOffset=50 PageSize=A4 GammaFile=Default pnmFormat=PixMap TopMargin=10 Bidirectional=Default RightMargin=10 BottomMargin=150 Blackness=2 job-uuid=urn:uuid:0898cc97-2133-3178-711a-e0d5b9e7e8ce print-color-mode=monochrome job-originating-host-name=localhost time-at-creation=1329852284 time-at-processing=1329852284 AP_D_InputSlot=" D [21/Feb/2012:20:24:44 +0100] [Job 137] argv[6]="/var/spool/cups/d00137-001" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[1]="CUPS_DATADIR=/usr/share/cups" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[6]="CUPS_SERVERROOT=/etc/cups" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[7]="CUPS_STATEDIR=/var/run/cups" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[8]="HOME=/var/spool/cups/tmp" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[10]="SERVER_ADMIN=root@ecosimplix" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[11]="SOFTWARE=CUPS/1.5.2" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[12]="TMPDIR=/var/spool/cups/tmp" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[13]="USER=root" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[15]="CUPS_ENCRYPTION=IfRequested" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[16]="IPP_PORT=631" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[17]="CHARSET=utf-8" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[18]="LANG=cs_CZ.UTF-8" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[19]="PPD=/etc/cups/ppd/DESKJET-710C.ppd" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[20]="RIP_MAX_CACHE=128m" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[21]="CONTENT_TYPE=application/pdf" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[22]="DEVICE_URI=usb://HP/DESKJET%20710C" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[23]="PRINTER_INFO=HEWLETT-PACKARD DESKJET 710C" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[24]="PRINTER_LOCATION=ecosimplix" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[25]="PRINTER=DESKJET-710C" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[26]="PRINTER_STATE_REASONS=none" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[27]="CUPS_FILETYPE=document" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[28]="FINAL_CONTENT_TYPE=printer/DESKJET-710C" D [21/Feb/2012:20:24:44 +0100] [Job 137] envp[29]="AUTH_I****" I [21/Feb/2012:20:24:44 +0100] [Job 137] Started filter /usr/lib/cups/filter/pdftopdf (PID 1646) I [21/Feb/2012:20:24:44 +0100] [Job 137] Started filter /usr/lib/cups/filter/foomatic-rip (PID 1647) I [21/Feb/2012:20:24:44 +0100] [Job 137] Started backend /usr/lib/cups/backend/usb (PID 1648) D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] Getting input from file D [21/Feb/2012:20:24:44 +0100] [Job 137] foomatic-rip version 4.0.12.246 running... D [21/Feb/2012:20:24:44 +0100] [Job 137] Parsing PPD file ... D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option ColorSpace D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option Resolution D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option PageSize D [21/Feb/2012:20:24:44 +0100] [Job 137] print_device D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option ImageableArea D [21/Feb/2012:20:24:44 +0100] [Job 137] libusb_get_device_list=12 D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option PaperDimension D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option ColorMode D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option EconoFast D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option Dither D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option Bidirectional D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option Blackness D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option TopMargin D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option BottomMargin D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option LeftMargin D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option RightMargin D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option XOffset D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option YOffset D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option Model D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option pnmFormat D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option GammaFile D [21/Feb/2012:20:24:44 +0100] [Job 137] Added option Font D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] Parameter Summary D [21/Feb/2012:20:24:44 +0100] [Job 137] ----------------- D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] Spooler: cups D [21/Feb/2012:20:24:44 +0100] [Job 137] Printer: DESKJET-710C D [21/Feb/2012:20:24:44 +0100] [Job 137] Shell: /bin/bash D [21/Feb/2012:20:24:44 +0100] [Job 137] PPD file: /etc/cups/ppd/DESKJET-710C.ppd D [21/Feb/2012:20:24:44 +0100] [Job 137] ATTR file: D [21/Feb/2012:20:24:44 +0100] [Job 137] Printer model: HP DeskJet 710C Foomatic/pnm2ppa (recommended) D [21/Feb/2012:20:24:44 +0100] [Job 137] Job title: Leafpad: úloha č. 2 D [21/Feb/2012:20:24:44 +0100] [Job 137] File(s) to be printed: D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [21/Feb/2012:20:24:44 +0100] [Job 137] Printing system options: D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'number-up=1' D [21/Feb/2012:20:24:44 +0100] [Job 137] Unknown option number-up=1. D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'job-uuid=urn:uuid:0898cc97-2133-3178-711a-e0d5b9e7e8ce' D [21/Feb/2012:20:24:44 +0100] [Job 137] Unknown option job-uuid=urn:uuid:0898cc97-2133-3178-711a-e0d5b9e7e8ce. D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'print-color-mode=monochrome' D [21/Feb/2012:20:24:44 +0100] [Job 137] Unknown option print-color-mode=monochrome. D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'job-originating-host-name=localhost' D [21/Feb/2012:20:24:44 +0100] [Job 137] Unknown option job-originating-host-name=localhost. D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'time-at-creation=1329852284' D [21/Feb/2012:20:24:44 +0100] [Job 137] Unknown option time-at-creation=1329852284. D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'time-at-processing=1329852284' D [21/Feb/2012:20:24:44 +0100] [Job 137] Unknown option time-at-processing=1329852284. D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'AP_D_InputSlot=' D [21/Feb/2012:20:24:44 +0100] [Job 137] Unknown option AP_D_InputSlot=. D [21/Feb/2012:20:24:44 +0100] [Job 137] Options from the PPD file: D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'ColorMode=Grayscale' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'XOffset=150' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'Dither=FloydSteinberg' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'EconoFast=On' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'LeftMargin=10' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'YOffset=50' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'PageSize=A4' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'GammaFile=Default' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'pnmFormat=PixMap' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'TopMargin=10' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'Bidirectional=Default' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'RightMargin=10' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'BottomMargin=150' D [21/Feb/2012:20:24:44 +0100] [Job 137] Pondering option 'Blackness=2' D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] ================================================ D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] File: D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] ================================================ D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] Filetype: PDF D [21/Feb/2012:20:24:44 +0100] [Job 137] Storing temporary files in /var/spool/cups/tmp D [21/Feb/2012:20:24:44 +0100] PID 1646 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [21/Feb/2012:20:24:44 +0100] [Job 137] Error opening file for reading: Permission denied D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:24:44 +0100] Get-Notifications / D [21/Feb/2012:20:24:44 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:24:44 +0100] Get-Job-Attributes ipp://localhost/jobs/137 D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/137) from localhost D [21/Feb/2012:20:24:44 +0100] [Job 137] File contains 1 pages D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] Starting renderer with command: gs -dFirstPage=1 -q -dNOPAUSE -dNOINTERPOLATE -dPARANOIDSAFER -dBATCH -r600 -sDEVICE=ppmraw -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=- /var/spool/cups/tmp/foomatic-hSAc4r | pnm2ppa -v 710 --bw --eco -B 2 -t 10 -b 150 -l 10 -r 10 -x 150 -y 50 -i - -o - D [21/Feb/2012:20:24:44 +0100] [Job 137] Starting process "kid3" (generation 1) D [21/Feb/2012:20:24:44 +0100] [Job 137] Starting process "kid4" (generation 2) D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] [Job 137] Starting process "renderer" (generation 2) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 1.1 Get-Printer-Attributes 1 D [21/Feb/2012:20:24:44 +0100] Get-Printer-Attributes D [21/Feb/2012:20:24:44 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist. D [21/Feb/2012:20:24:44 +0100] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 22 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 22 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 22 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:24:44 +0100] Get-Job-Attributes ipp://localhost/jobs/137 D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/137) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 22 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 22 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:24:44 +0100] Get-Job-Attributes ipp://localhost/jobs/137 D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/137) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] JCL: %-12345X@PJL D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] [Job 137] D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:24:44 +0100] Get-Job-Attributes ipp://localhost/jobs/137 D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/137) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] Error opening file for reading: Permission denied D [21/Feb/2012:20:24:44 +0100] [Job 137] STATE: +connecting-to-device D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] STATE: -connecting-to-device D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" I [21/Feb/2012:20:24:44 +0100] [Job 137] Sending data to printer. D [21/Feb/2012:20:24:44 +0100] [Job 137] Set job-printer-state-message to "Sending data to printer.", current level=INFO D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] [Job 137] Read 9 bytes of back-channel data... D [21/Feb/2012:20:24:44 +0100] [Job 137] Read 3 bytes of back-channel data... D [21/Feb/2012:20:24:44 +0100] [Job 137] Read 3 bytes of back-channel data... D [21/Feb/2012:20:24:44 +0100] [Job 137] Read 3 bytes of back-channel data... D [21/Feb/2012:20:24:44 +0100] [Job 137] Read 38 bytes of back-channel data... D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:24:44 +0100] Get-Notifications / D [21/Feb/2012:20:24:44 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:24:44 +0100] Get-Job-Attributes ipp://localhost/jobs/137 D [21/Feb/2012:20:24:44 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/137) from localhost D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:44 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:44 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:45 +0100] [Job 137] renderer exited with status 0 D [21/Feb/2012:20:24:45 +0100] [Job 137] kid4 exited with status 0 D [21/Feb/2012:20:24:45 +0100] [Job 137] kid3 finished D [21/Feb/2012:20:24:45 +0100] [Job 137] Read 3276 bytes of print data... D [21/Feb/2012:20:24:45 +0100] [Job 137] Kid3 exit status: 0 D [21/Feb/2012:20:24:45 +0100] [Job 137] D [21/Feb/2012:20:24:45 +0100] [Job 137] Closing foomatic-rip. D [21/Feb/2012:20:24:45 +0100] PID 1647 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [21/Feb/2012:20:24:45 +0100] [Job 137] Wrote 3276 bytes of print data... D [21/Feb/2012:20:24:45 +0100] [Job 137] Sent 3276 bytes... D [21/Feb/2012:20:24:45 +0100] [Job 137] Waiting for read thread to exit... D [21/Feb/2012:20:24:45 +0100] PID 1648 (/usr/lib/cups/backend/usb) exited with no errors. D [21/Feb/2012:20:24:45 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [21/Feb/2012:20:24:45 +0100] [Job 137] Job completed. D [21/Feb/2012:20:24:45 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:45 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:45 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:24:45 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:24:45 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:45 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:24:45 +0100] Get-Notifications / D [21/Feb/2012:20:24:45 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:24:45 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:45 +0100] cupsdAcceptClient: 20 from localhost (Domain) D [21/Feb/2012:20:24:45 +0100] cupsdReadClient: 20 POST / HTTP/1.1 D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:24:45 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:24:45 +0100] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1 D [21/Feb/2012:20:24:45 +0100] Get-Printer-Attributes ipp://ecosimplix:631/printers/DESKJET-710C D [21/Feb/2012:20:24:45 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://ecosimplix:631/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:24:45 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:24:45 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:24:45 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:24:46 +0100] [Job 137] Unloading... I [21/Feb/2012:20:25:15 +0100] Saving job.cache... I [21/Feb/2012:20:25:16 +0100] Saving subscriptions.conf... D [21/Feb/2012:20:25:16 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [21/Feb/2012:20:25:16 +0100] Report: clients=4 D [21/Feb/2012:20:25:16 +0100] Report: jobs=2 D [21/Feb/2012:20:25:16 +0100] Report: jobs-active=0 D [21/Feb/2012:20:25:16 +0100] Report: printers=1 D [21/Feb/2012:20:25:16 +0100] Report: printers-implicit=0 D [21/Feb/2012:20:25:16 +0100] Report: stringpool-string-count=2924 D [21/Feb/2012:20:25:16 +0100] Report: stringpool-alloc-bytes=9528 D [21/Feb/2012:20:25:16 +0100] Report: stringpool-total-bytes=60544 D [21/Feb/2012:20:25:17 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:25:17 +0100] cupsdNetIFUpdate: "eth0" = 192.168.0.9:631 D [21/Feb/2012:20:25:17 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [21/Feb/2012:20:25:17 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::1e6f:65ff:fec8:1b71+eth0]:631 D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:25:21 +0100] CUPS-Get-Printers D [21/Feb/2012:20:25:21 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 17 GET /printers/DESKJET-710C.ppd HTTP/1.1 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:25:21 +0100] CUPS-Get-Printers D [21/Feb/2012:20:25:21 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:25:21 +0100] CUPS-Get-Printers D [21/Feb/2012:20:25:21 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:25:21 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1 D [21/Feb/2012:20:25:21 +0100] CUPS-Get-Printers D [21/Feb/2012:20:25:21 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [21/Feb/2012:20:25:21 +0100] cupsdReadClient: 21 WAITING Closing on EOF D [21/Feb/2012:20:25:21 +0100] cupsdCloseClient: 21 D [21/Feb/2012:20:25:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 POST /printers/DESKJET-710C HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 1.1 Print-Job 1 D [21/Feb/2012:20:25:22 +0100] Print-Job ipp://localhost:631/printers/DESKJET-710C D [21/Feb/2012:20:25:22 +0100] [Job ???] Auto-typing file... I [21/Feb/2012:20:25:22 +0100] [Job ???] Request file type is application/pdf. D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [21/Feb/2012:20:25:22 +0100] add_job: requesting-user-name="pavel" I [21/Feb/2012:20:25:22 +0100] [Job 138] Adding start banner page "none". D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/Feb/2012:20:25:22 +0100] [Job 138] Adding end banner page "none". I [21/Feb/2012:20:25:22 +0100] [Job 138] File of type application/pdf queued by "pavel". D [21/Feb/2012:20:25:22 +0100] [Job 138] hold_until=0 I [21/Feb/2012:20:25:22 +0100] [Job 138] Queued on "DESKJET-710C" by "pavel". D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:25:22 +0100] [Job 138] job-sheets=none,none D [21/Feb/2012:20:25:22 +0100] [Job 138] argv[0]="DESKJET-710C" D [21/Feb/2012:20:25:22 +0100] [Job 138] argv[1]="138" D [21/Feb/2012:20:25:22 +0100] [Job 138] argv[2]="pavel" D [21/Feb/2012:20:25:22 +0100] [Job 138] argv[3]="Leafpad: úloha č. 3" D [21/Feb/2012:20:25:22 +0100] [Job 138] argv[4]="1" D [21/Feb/2012:20:25:22 +0100] [Job 138] argv[5]="number-up=1 ColorMode=Grayscale XOffset=150 Dither=FloydSteinberg EconoFast=On LeftMargin=10 YOffset=50 PageSize=A4 GammaFile=Default pnmFormat=PixMap TopMargin=10 Bidirectional=Default RightMargin=10 BottomMargin=150 Blackness=2 job-uuid=urn:uuid:237dd962-f9a7-3104-68d3-b372640af0fb print-color-mode=monochrome job-originating-host-name=localhost time-at-creation=1329852322 time-at-processing=1329852322 AP_D_InputSlot=" D [21/Feb/2012:20:25:22 +0100] [Job 138] argv[6]="/var/spool/cups/d00138-001" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[1]="CUPS_DATADIR=/usr/share/cups" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[6]="CUPS_SERVERROOT=/etc/cups" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[7]="CUPS_STATEDIR=/var/run/cups" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[8]="HOME=/var/spool/cups/tmp" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[10]="SERVER_ADMIN=root@ecosimplix" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[11]="SOFTWARE=CUPS/1.5.2" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[12]="TMPDIR=/var/spool/cups/tmp" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[13]="USER=root" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[15]="CUPS_ENCRYPTION=IfRequested" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[16]="IPP_PORT=631" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[17]="CHARSET=utf-8" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[18]="LANG=cs_CZ.UTF-8" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[19]="PPD=/etc/cups/ppd/DESKJET-710C.ppd" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[20]="RIP_MAX_CACHE=128m" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[21]="CONTENT_TYPE=application/pdf" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[22]="DEVICE_URI=usb://HP/DESKJET%20710C" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[23]="PRINTER_INFO=HEWLETT-PACKARD DESKJET 710C" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[24]="PRINTER_LOCATION=ecosimplix" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[25]="PRINTER=DESKJET-710C" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[26]="PRINTER_STATE_REASONS=none" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[27]="CUPS_FILETYPE=document" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[28]="FINAL_CONTENT_TYPE=printer/DESKJET-710C" D [21/Feb/2012:20:25:22 +0100] [Job 138] envp[29]="AUTH_I****" I [21/Feb/2012:20:25:22 +0100] [Job 138] Started filter /usr/lib/cups/filter/pdftopdf (PID 1665) I [21/Feb/2012:20:25:22 +0100] [Job 138] Started filter /usr/lib/cups/filter/foomatic-rip (PID 1666) I [21/Feb/2012:20:25:22 +0100] [Job 138] Started backend /usr/lib/cups/backend/usb (PID 1667) D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] [Job 138] Getting input from file D [21/Feb/2012:20:25:22 +0100] [Job 138] foomatic-rip version 4.0.12.246 running... D [21/Feb/2012:20:25:22 +0100] [Job 138] Parsing PPD file ... D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option ColorSpace D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option Resolution D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option PageSize D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option ImageableArea D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option PaperDimension D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option ColorMode D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option EconoFast D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option Dither D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option Bidirectional D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option Blackness D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option TopMargin D [21/Feb/2012:20:25:22 +0100] [Job 138] print_device D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option BottomMargin D [21/Feb/2012:20:25:22 +0100] [Job 138] libusb_get_device_list=12 D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option LeftMargin D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option RightMargin D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option XOffset D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option YOffset D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option Model D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option pnmFormat D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option GammaFile D [21/Feb/2012:20:25:22 +0100] [Job 138] Added option Font D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] Parameter Summary D [21/Feb/2012:20:25:22 +0100] [Job 138] ----------------- D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] Spooler: cups D [21/Feb/2012:20:25:22 +0100] [Job 138] Printer: DESKJET-710C D [21/Feb/2012:20:25:22 +0100] [Job 138] Shell: /bin/bash D [21/Feb/2012:20:25:22 +0100] [Job 138] PPD file: /etc/cups/ppd/DESKJET-710C.ppd D [21/Feb/2012:20:25:22 +0100] [Job 138] ATTR file: D [21/Feb/2012:20:25:22 +0100] [Job 138] Printer model: HP DeskJet 710C Foomatic/pnm2ppa (recommended) D [21/Feb/2012:20:25:22 +0100] [Job 138] Job title: Leafpad: úloha č. 3 D [21/Feb/2012:20:25:22 +0100] [Job 138] File(s) to be printed: D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [21/Feb/2012:20:25:22 +0100] [Job 138] Printing system options: D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'number-up=1' D [21/Feb/2012:20:25:22 +0100] [Job 138] Unknown option number-up=1. D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'job-uuid=urn:uuid:237dd962-f9a7-3104-68d3-b372640af0fb' D [21/Feb/2012:20:25:22 +0100] [Job 138] Unknown option job-uuid=urn:uuid:237dd962-f9a7-3104-68d3-b372640af0fb. D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'print-color-mode=monochrome' D [21/Feb/2012:20:25:22 +0100] [Job 138] Unknown option print-color-mode=monochrome. D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'job-originating-host-name=localhost' D [21/Feb/2012:20:25:22 +0100] [Job 138] Unknown option job-originating-host-name=localhost. D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'time-at-creation=1329852322' D [21/Feb/2012:20:25:22 +0100] [Job 138] Unknown option time-at-creation=1329852322. D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'time-at-processing=1329852322' D [21/Feb/2012:20:25:22 +0100] [Job 138] Unknown option time-at-processing=1329852322. D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'AP_D_InputSlot=' D [21/Feb/2012:20:25:22 +0100] [Job 138] Unknown option AP_D_InputSlot=. D [21/Feb/2012:20:25:22 +0100] [Job 138] Options from the PPD file: D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'ColorMode=Grayscale' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'XOffset=150' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'Dither=FloydSteinberg' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'EconoFast=On' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'LeftMargin=10' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'YOffset=50' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'PageSize=A4' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'GammaFile=Default' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'pnmFormat=PixMap' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'TopMargin=10' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'Bidirectional=Default' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'RightMargin=10' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'BottomMargin=150' D [21/Feb/2012:20:25:22 +0100] [Job 138] Pondering option 'Blackness=2' D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] ================================================ D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] File: D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] ================================================ D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] Filetype: PDF D [21/Feb/2012:20:25:22 +0100] [Job 138] Storing temporary files in /var/spool/cups/tmp D [21/Feb/2012:20:25:22 +0100] PID 1665 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [21/Feb/2012:20:25:22 +0100] [Job 138] Error opening file for reading: Permission denied D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] [Job 138] File contains 1 pages D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] [Job 138] Starting renderer with command: gs -dFirstPage=1 -q -dNOPAUSE -dNOINTERPOLATE -dPARANOIDSAFER -dBATCH -r600 -sDEVICE=ppmraw -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=- /var/spool/cups/tmp/foomatic-DZSLxn | pnm2ppa -v 710 --bw --eco -B 2 -t 10 -b 150 -l 10 -r 10 -x 150 -y 50 -i - -o - D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:25:22 +0100] Get-Notifications / D [21/Feb/2012:20:25:22 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:25:22 +0100] [Job 138] Starting process "kid3" (generation 1) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:25:22 +0100] Get-Job-Attributes ipp://localhost/jobs/138 D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/138) from localhost D [21/Feb/2012:20:25:22 +0100] [Job 138] Starting process "kid4" (generation 2) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] [Job 138] Starting process "renderer" (generation 2) D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 22 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] [Job 138] JCL: %-12345X@PJL D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] [Job 138] D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 1.1 Get-Printer-Attributes 1 D [21/Feb/2012:20:25:22 +0100] Get-Printer-Attributes D [21/Feb/2012:20:25:22 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist. D [21/Feb/2012:20:25:22 +0100] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 23 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 23 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:25:22 +0100] Get-Job-Attributes ipp://localhost/jobs/138 D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/138) from localhost D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 23 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 23 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] [Job 138] Error opening file for reading: Permission denied D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 22 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 22 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:25:22 +0100] Get-Job-Attributes ipp://localhost/jobs/138 D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/138) from localhost D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 22 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 22 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:25:22 +0100] Get-Job-Attributes ipp://localhost/jobs/138 D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/138) from localhost D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 22 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] [Job 138] STATE: +connecting-to-device D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] [Job 138] STATE: -connecting-to-device D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" I [21/Feb/2012:20:25:22 +0100] [Job 138] Sending data to printer. D [21/Feb/2012:20:25:22 +0100] [Job 138] Set job-printer-state-message to "Sending data to printer.", current level=INFO D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] [Job 138] Read 56 bytes of back-channel data... D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:25:22 +0100] Get-Notifications / D [21/Feb/2012:20:25:22 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAcceptClient: 22 from localhost (Domain) D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 POST / HTTP/1.1 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 1.1 Get-Job-Attributes 1 D [21/Feb/2012:20:25:22 +0100] Get-Job-Attributes ipp://localhost/jobs/138 D [21/Feb/2012:20:25:22 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/138) from localhost D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 22 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 22 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:22 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:22 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:23 +0100] [Job 138] renderer exited with status 0 D [21/Feb/2012:20:25:23 +0100] [Job 138] Read 3280 bytes of print data... D [21/Feb/2012:20:25:23 +0100] [Job 138] kid4 exited with status 0 D [21/Feb/2012:20:25:23 +0100] [Job 138] kid3 finished D [21/Feb/2012:20:25:23 +0100] [Job 138] Kid3 exit status: 0 D [21/Feb/2012:20:25:23 +0100] [Job 138] D [21/Feb/2012:20:25:23 +0100] [Job 138] Closing foomatic-rip. D [21/Feb/2012:20:25:23 +0100] PID 1666 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [21/Feb/2012:20:25:23 +0100] [Job 138] Wrote 3280 bytes of print data... D [21/Feb/2012:20:25:23 +0100] [Job 138] Sent 3280 bytes... D [21/Feb/2012:20:25:23 +0100] [Job 138] Waiting for read thread to exit... D [21/Feb/2012:20:25:23 +0100] PID 1667 (/usr/lib/cups/backend/usb) exited with no errors. D [21/Feb/2012:20:25:23 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [21/Feb/2012:20:25:23 +0100] [Job 138] Job completed. D [21/Feb/2012:20:25:23 +0100] cupsdMarkDirty(----J-) D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:23 +0100] cupsdMarkDirty(-----S) D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:23 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [21/Feb/2012:20:25:23 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [21/Feb/2012:20:25:23 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:23 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1 D [21/Feb/2012:20:25:23 +0100] Get-Notifications / D [21/Feb/2012:20:25:23 +0100] cupsdIsAuthorized: requesting-user-name="pavel" D [21/Feb/2012:20:25:23 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:25:23 +0100] cupsdAcceptClient: 21 from localhost (Domain) D [21/Feb/2012:20:25:23 +0100] cupsdReadClient: 21 POST / HTTP/1.1 D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/Feb/2012:20:25:23 +0100] cupsdAuthorize: No authentication data provided. D [21/Feb/2012:20:25:23 +0100] cupsdReadClient: 21 1.1 Get-Printer-Attributes 1 D [21/Feb/2012:20:25:23 +0100] Get-Printer-Attributes ipp://ecosimplix:631/printers/DESKJET-710C D [21/Feb/2012:20:25:23 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://ecosimplix:631/printers/DESKJET-710C) from localhost D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/Feb/2012:20:25:23 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [21/Feb/2012:20:25:23 +0100] cupsdCloseClient: 17 D [21/Feb/2012:20:25:23 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/Feb/2012:20:25:24 +0100] [Job 138] Unloading... I [21/Feb/2012:20:25:53 +0100] Saving job.cache... I [21/Feb/2012:20:25:53 +0100] Saving subscriptions.conf... D [21/Feb/2012:20:25:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"