Bug#736942: cups: Printing not working with Konica Minolta C220

To: Debian Bug Tracking System <submit@bugs.debian.org>
Subject: Bug#736942: cups: Printing not working with Konica Minolta C220
From: Oriol Mula-Valls <oriol.mula-valls@ic3.cat>
Date: Tue, 28 Jan 2014 17:50:48 +0100
Message-id: <[🔎] 20140128165048.12584.54837.reportbug@freyr.ic3.cat>
Reply-to: Oriol Mula-Valls <oriol.mula-valls@ic3.cat>, 736942@bugs.debian.org

Package: cups
Version: 1.5.3-5+deb7u1
Severity: important

Dear Maintainer,
*** Please consider answering these questions, where appropriate ***

   * What led up to the situation?
Upgrading from squeeze to wheezy
   * What exactly did you do (or not do) that was effective (or
     ineffective)?
Printing wasn't working and I tried replacing the ipp backend with ipp14 but
didn't work.
   * What was the outcome of this action?
Error message in printer job history: Deleted Due To Error
   * What outcome did you expect instead?
Document printed

*** End of the template - remove these lines ***



-- System Information:
Debian Release: 7.2
  APT prefers stable
  APT policy: (900, 'stable')
Architecture: amd64 (x86_64)
Foreign Architectures: i386

Kernel: Linux 3.2.0-4-amd64 (SMP w/8 CPU cores)
Locale: LANG=en_GB.UTF-8, LC_CTYPE=en_GB.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages cups depends on:
ii  adduser                3.113+nmu3
ii  bc                     1.06.95-2+b1
ii  cups-client            1.5.3-5+deb7u1
ii  cups-common            1.5.3-5+deb7u1
ii  cups-filters           1.0.18-2.1
ii  cups-ppdc              1.5.3-5+deb7u1
ii  debconf [debconf-2.0]  1.5.49
ii  dpkg                   1.16.12
ii  ghostscript            9.05~dfsg-6.3+deb7u1
ii  libavahi-client3       0.6.31-2
ii  libavahi-common3       0.6.31-2
ii  libc-bin               2.13-38
ii  libc6                  2.13-38
ii  libcups2               1.5.3-5+deb7u1
ii  libcupscgi1            1.5.3-5+deb7u1
ii  libcupsimage2          1.5.3-5+deb7u1
ii  libcupsmime1           1.5.3-5+deb7u1
ii  libcupsppdc1           1.5.3-5+deb7u1
ii  libdbus-1-3            1.6.8-1+deb7u1
ii  libgcc1                1:4.7.2-5
ii  libgnutls26            2.12.20-7
ii  libgssapi-krb5-2       1.10.1+dfsg-5+deb7u1
ii  libkrb5-3              1.10.1+dfsg-5+deb7u1
ii  libldap-2.4-2          2.4.31-1+nmu2
ii  libpam0g               1.1.3-7.1
ii  libpaper1              1.1.24+nmu2
ii  libslp1                1.2.1-9
ii  libstdc++6             4.7.2-5
ii  libusb-1.0-0           2:1.0.11-1
ii  lsb-base               4.1+Debian8+deb7u1
ii  poppler-utils          0.18.4-6
ii  procps                 1:3.3.3-3
ii  ssl-cert               1.0.32

Versions of packages cups recommends:
ii  avahi-daemon               0.6.31-2
ii  colord                     0.1.21-1
ii  foomatic-filters           4.0.17-1
ii  ghostscript-cups           9.05~dfsg-6.3+deb7u1
ii  printer-driver-gutenprint  5.2.9-1

Versions of packages cups suggests:
ii  cups-bsd               1.5.3-5+deb7u1
pn  cups-pdf               <none>
ii  foomatic-db            20120523-1
ii  hplip                  3.12.6-3.1
ii  printer-driver-hpcups  3.12.6-3.1
ii  smbclient              2:3.6.6-6+deb7u1
ii  udev                   175-7.2

-- debconf information:
  cupsys/raw-print: true
  cupsys/backend: ipp, ipp14, lpd, socket, usb, snmp, dnssd

I [28/Jan/2014:17:36:48 +0100] Saving printers.conf...
I [28/Jan/2014:17:36:48 +0100] Generating printcap /var/run/cups/printcap...
I [28/Jan/2014:17:36:48 +0100] Saving subscriptions.conf...
D [28/Jan/2014:17:36:48 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [28/Jan/2014:17:36:48 +0100] Saving job.cache...
W [28/Jan/2014:17:36:49 +0100] Please move "SystemGroup lpadmin" on line 3 of /etc/cups/cupsd.conf to the /etc/cups/cups-files.conf file; this will become an error in a future release.
I [28/Jan/2014:17:36:49 +0100] Listening to [v1.::1]:631 (IPv6)
I [28/Jan/2014:17:36:49 +0100] Listening to 127.0.0.1:631 (IPv4)
I [28/Jan/2014:17:36:49 +0100] Listening to /var/run/cups/cups.sock (Domain)
W [28/Jan/2014:17:36:49 +0100] No limit for Validate-Job defined in policy default and no suitable template found.
W [28/Jan/2014:17:36:49 +0100] No limit for Cancel-Jobs defined in policy default - using Pause-Printer's policy.
W [28/Jan/2014:17:36:49 +0100] No limit for Cancel-My-Jobs defined in policy default - using Send-Document's policy.
W [28/Jan/2014:17:36:49 +0100] No limit for Close-Job defined in policy default - using Send-Document's policy.
W [28/Jan/2014:17:36:49 +0100] No JobPrivateAccess defined in policy default - using defaults.
W [28/Jan/2014:17:36:49 +0100] No JobPrivateValues defined in policy default - using defaults.
W [28/Jan/2014:17:36:49 +0100] No SubscriptionPrivateAccess defined in policy default - using defaults.
W [28/Jan/2014:17:36:49 +0100] No SubscriptionPrivateValues defined in policy default - using defaults.
W [28/Jan/2014:17:36:49 +0100] No limit for Validate-Job defined in policy authenticated - using Print-Job's policy.
W [28/Jan/2014:17:36:49 +0100] No limit for Cancel-Jobs defined in policy authenticated - using Pause-Printer's policy.
W [28/Jan/2014:17:36:49 +0100] No limit for Cancel-My-Jobs defined in policy authenticated - using Send-Document's policy.
W [28/Jan/2014:17:36:49 +0100] No limit for Close-Job defined in policy authenticated - using Send-Document's policy.
W [28/Jan/2014:17:36:49 +0100] No JobPrivateAccess defined in policy authenticated - using defaults.
W [28/Jan/2014:17:36:49 +0100] No JobPrivateValues defined in policy authenticated - using defaults.
W [28/Jan/2014:17:36:49 +0100] No SubscriptionPrivateAccess defined in policy authenticated - using defaults.
W [28/Jan/2014:17:36:49 +0100] No SubscriptionPrivateValues defined in policy authenticated - using defaults.
I [28/Jan/2014:17:36:49 +0100] Remote access is disabled.
D [28/Jan/2014:17:36:49 +0100] Added auto ServerAlias freyr
I [28/Jan/2014:17:36:49 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [28/Jan/2014:17:36:49 +0100] Using default TempDir of /var/spool/cups/tmp...
I [28/Jan/2014:17:36:49 +0100] Configured for up to 100 clients.
I [28/Jan/2014:17:36:49 +0100] Allowing up to 100 client connections per host.
I [28/Jan/2014:17:36:49 +0100] Using policy "default" as the default.
I [28/Jan/2014:17:36:49 +0100] Full reload is required.
I [28/Jan/2014:17:36:49 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 40 types, 57 filters...
D [28/Jan/2014:17:36:49 +0100] Loading printer KONICA-MINOLTA-C360SeriesPS-P...
D [28/Jan/2014:17:36:49 +0100] load_ppd: Loading /var/cache/cups/KONICA-MINOLTA-C360SeriesPS-P.data...
D [28/Jan/2014:17:36:49 +0100] Calling DeleteDevice(cups-KONICA-MINOLTA-C360SeriesPS-P)
D [28/Jan/2014:17:36:49 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [28/Jan/2014:17:36:49 +0100] Using profile id of KONICA-MINOLTA-C360SeriesPS-P-Gray..
D [28/Jan/2014:17:36:49 +0100] Calling CreateProfile(KONICA-MINOLTA-C360SeriesPS-P-Gray..,temp)
D [28/Jan/2014:17:36:49 +0100] created profile /org/freedesktop/ColorManager/profiles/KONICA_MINOLTA_C360SeriesPS_P_Gray__
D [28/Jan/2014:17:36:49 +0100] Using profile id of KONICA-MINOLTA-C360SeriesPS-P-CMYK..
D [28/Jan/2014:17:36:49 +0100] Calling CreateProfile(KONICA-MINOLTA-C360SeriesPS-P-CMYK..,temp)
D [28/Jan/2014:17:36:49 +0100] created profile /org/freedesktop/ColorManager/profiles/KONICA_MINOLTA_C360SeriesPS_P_CMYK__
I [28/Jan/2014:17:36:49 +0100] Registering ICC color profiles for "KONICA-MINOLTA-C360SeriesPS-P"
D [28/Jan/2014:17:36:49 +0100] Calling CreateDevice(cups-KONICA-MINOLTA-C360SeriesPS-P,temp)
D [28/Jan/2014:17:36:49 +0100] created device /org/freedesktop/ColorManager/devices/cups_KONICA_MINOLTA_C360SeriesPS_P
D [28/Jan/2014:17:36:49 +0100] Calling /org/freedesktop/ColorManager/devices/cups_KONICA_MINOLTA_C360SeriesPS_P:AddProfile(/org/freedesktop/ColorManager/profiles/KONICA_MINOLTA_C360SeriesPS_P_Gray__) [soft]
D [28/Jan/2014:17:36:49 +0100] Calling /org/freedesktop/ColorManager/devices/cups_KONICA_MINOLTA_C360SeriesPS_P:AddProfile(/org/freedesktop/ColorManager/profiles/KONICA_MINOLTA_C360SeriesPS_P_CMYK__) [soft]
D [28/Jan/2014:17:36:49 +0100] cupsdRegisterPrinter(p=0x7f6e387e4040(KONICA-MINOLTA-C360SeriesPS-P))
D [28/Jan/2014:17:36:49 +0100] load_ppd: Loading /var/cache/cups/KONICA-MINOLTA-C360SeriesPS-P.data...
D [28/Jan/2014:17:36:49 +0100] Calling DeleteDevice(cups-KONICA-MINOLTA-C360SeriesPS-P)
D [28/Jan/2014:17:36:49 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [28/Jan/2014:17:36:49 +0100] Using profile id of KONICA-MINOLTA-C360SeriesPS-P-Gray..
D [28/Jan/2014:17:36:49 +0100] Calling CreateProfile(KONICA-MINOLTA-C360SeriesPS-P-Gray..,temp)
W [28/Jan/2014:17:36:49 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'KONICA-MINOLTA-C360SeriesPS-P-Gray..' already exists
D [28/Jan/2014:17:36:49 +0100] Using profile id of KONICA-MINOLTA-C360SeriesPS-P-CMYK..
D [28/Jan/2014:17:36:49 +0100] Calling CreateProfile(KONICA-MINOLTA-C360SeriesPS-P-CMYK..,temp)
W [28/Jan/2014:17:36:49 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'KONICA-MINOLTA-C360SeriesPS-P-CMYK..' already exists
I [28/Jan/2014:17:36:49 +0100] Registering ICC color profiles for "KONICA-MINOLTA-C360SeriesPS-P"
D [28/Jan/2014:17:36:49 +0100] Calling CreateDevice(cups-KONICA-MINOLTA-C360SeriesPS-P,temp)
W [28/Jan/2014:17:36:49 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-KONICA-MINOLTA-C360SeriesPS-P' already exists
D [28/Jan/2014:17:36:49 +0100] cupsdRegisterPrinter(p=0x7f6e387e4040(KONICA-MINOLTA-C360SeriesPS-P))
I [28/Jan/2014:17:36:49 +0100] Loading job cache file "/var/cache/cups/job.cache"...
D [28/Jan/2014:17:36:49 +0100] [Job 235] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 236] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 237] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 238] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 239] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 240] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 241] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 242] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 243] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 244] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 245] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] [Job 247] Loading from cache...
D [28/Jan/2014:17:36:49 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [28/Jan/2014:17:36:49 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [28/Jan/2014:17:36:49 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [28/Jan/2014:17:36:49 +0100] Full reload complete.
D [28/Jan/2014:17:36:49 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [28/Jan/2014:17:36:49 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
D [28/Jan/2014:17:36:49 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [28/Jan/2014:17:36:49 +0100] Cleaning out old files in "/var/cache/cups"...
I [28/Jan/2014:17:36:49 +0100] Listening to [v1.::1]:631 on fd 9...
I [28/Jan/2014:17:36:49 +0100] Listening to 127.0.0.1:631 on fd 10...
I [28/Jan/2014:17:36:49 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [28/Jan/2014:17:36:49 +0100] Resuming new connection processing...
D [28/Jan/2014:17:36:49 +0100] cupsdRegisterPrinter(p=0x7f6e387e4040(KONICA-MINOLTA-C360SeriesPS-P))
D [28/Jan/2014:17:36:49 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:49 +0100] Discarding unused server-started event...
D [28/Jan/2014:17:36:50 +0100] Report: clients=0
D [28/Jan/2014:17:36:50 +0100] Report: jobs=12
D [28/Jan/2014:17:36:50 +0100] Report: jobs-active=0
D [28/Jan/2014:17:36:50 +0100] Report: printers=1
D [28/Jan/2014:17:36:50 +0100] Report: printers-implicit=0
D [28/Jan/2014:17:36:50 +0100] Report: stringpool-string-count=15395
D [28/Jan/2014:17:36:50 +0100] Report: stringpool-alloc-bytes=11864
D [28/Jan/2014:17:36:50 +0100] Report: stringpool-total-bytes=275288
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:55 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:55 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 15 GET /printers/KONICA-MINOLTA-C360SeriesPS-P.ppd HTTP/1.1
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:55 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:55 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:55 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:55 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:55 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:55 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:55 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:55 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:55 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:55 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:55 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:56 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:56 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:56 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:56 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:56 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:56 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:56 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:56 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:56 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:56 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:56 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:56 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:56 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:57 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:57 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:57 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:57 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:57 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:57 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:57 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:57 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:57 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2014:17:36:57 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:36:57 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:36:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:36:58 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 POST /printers/KONICA-MINOLTA-C360SeriesPS-P HTTP/1.1
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2014:17:36:58 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 1.1 Print-Job 1
D [28/Jan/2014:17:36:58 +0100] Print-Job ipp://localhost:631/printers/KONICA-MINOLTA-C360SeriesPS-P
D [28/Jan/2014:17:36:58 +0100] [Job ???] Auto-typing file...
I [28/Jan/2014:17:36:58 +0100] [Job ???] Request file type is application/pdf.
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(----J-)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [28/Jan/2014:17:36:58 +0100] add_job: requesting-user-name="omula"
I [28/Jan/2014:17:36:58 +0100] [Job 248] Adding start banner page "none".
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-created event...
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(----J-)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [28/Jan/2014:17:36:58 +0100] [Job 248] Adding end banner page "none".
I [28/Jan/2014:17:36:58 +0100] [Job 248] File of type application/pdf queued by "omula".
D [28/Jan/2014:17:36:58 +0100] [Job 248] hold_until=0
I [28/Jan/2014:17:36:58 +0100] [Job 248] Queued on "KONICA-MINOLTA-C360SeriesPS-P" by "omula".
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(----J-)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] job-sheets=none,none
D [28/Jan/2014:17:36:58 +0100] [Job 248] argv[0]="KONICA-MINOLTA-C360SeriesPS-P"
D [28/Jan/2014:17:36:58 +0100] [Job 248] argv[1]="248"
D [28/Jan/2014:17:36:58 +0100] [Job 248] argv[2]="omula"
D [28/Jan/2014:17:36:58 +0100] [Job 248] argv[3]="linuxchecklist.pdf"
D [28/Jan/2014:17:36:58 +0100] [Job 248] argv[4]="1"
D [28/Jan/2014:17:36:58 +0100] [Job 248] argv[5]="noString4Pt OriginalImageType=Document InputSlot=AutoSelect GraphicSmoothing=Auto PaperSources=None FrontCoverTray=None Punch=None Fold=None BackCoverTray=None KOPunch=None GraphicPureBlack=Auto GraphicColorMatching=Auto GraphicScreen=Auto BlackOverPrint=Off TextScreen=Auto noGlossyMode TextPureBlack=Auto noTonerSave PhotoScreen=Auto PageSize=A4 SelectColor=Auto TextColorMatching=Auto SaddleUnit=None MediaType=Plain noOffset KMDuplex=2Sided WaitMode=None number-up=1 OHPOpTray=None PhotoColorMatching=Auto noAutoTrapping FrontCoverPage=None TransparencyInterleave=None PhotoSmoothing=Auto Combination=None BackCoverPage=None Finisher=None Staple=None PhotoPureBlack=Auto Binding=TopBinding OutputBin=Default job-uuid=urn:uuid:9a93c53b-8ba6-32ff-6a38-89c787d8ae46 job-originating-host-name=localhost time-at-creation=1390927018 time-at-processing=1390927018"
D [28/Jan/2014:17:36:58 +0100] [Job 248] argv[6]="/var/spool/cups/d00248-001"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[8]="HOME=/var/spool/cups/tmp"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[10]="SERVER_ADMIN=root@freyr"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[11]="SOFTWARE=CUPS/1.5.3"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[13]="TZ=Europe/Madrid"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[14]="USER=root"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[17]="IPP_PORT=631"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[18]="CHARSET=utf-8"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[19]="LANG=en_GB.UTF-8"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[20]="PPD=/etc/cups/ppd/KONICA-MINOLTA-C360SeriesPS-P.ppd"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[21]="RIP_MAX_CACHE=128m"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[22]="CONTENT_TYPE=application/pdf"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[23]="DEVICE_URI=ipp://192.168.50.2/ipp"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[24]="PRINTER_INFO=KONICA MINOLTA C360SeriesPS/P"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[25]="PRINTER_LOCATION=KMBT6CF574"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[26]="PRINTER=KONICA-MINOLTA-C360SeriesPS-P"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[27]="PRINTER_STATE_REASONS=media-empty-warning"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[28]="CUPS_FILETYPE=document"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [28/Jan/2014:17:36:58 +0100] [Job 248] envp[30]="AUTH_I****"
I [28/Jan/2014:17:36:58 +0100] [Job 248] Started filter /usr/lib/cups/filter/pdftopdf (PID 12516)
I [28/Jan/2014:17:36:58 +0100] [Job 248] Started filter /usr/lib/cups/filter/pdftops (PID 12517)
I [28/Jan/2014:17:36:58 +0100] [Job 248] Started backend /usr/lib/cups/backend/ipp (PID 12518)
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-state-changed event...
D [28/Jan/2014:17:36:58 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/KONICA-MINOLTA-C360SeriesPS-P) from localhost
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:58 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: +connecting-to-device
D [28/Jan/2014:17:36:58 +0100] [Job 248] Looking up "192.168.50.2"...
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
I [28/Jan/2014:17:36:58 +0100] [Job 248] Copying print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Set job-printer-state-message to "Copying print data...", current level=INFO
D [28/Jan/2014:17:36:58 +0100] [Job 248] 
D [28/Jan/2014:17:36:58 +0100] [Job 248] backendRunLoop(print_fd=-1, device_fd=6, snmp_fd=5, addr=0x7f458ffafa88, use_bc=0, side_cb=0x7f458f62de30)
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] pdftops - copying to temp print file "/var/spool/cups/tmp/030e552eac70c"
D [28/Jan/2014:17:36:58 +0100] PID 12516 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [28/Jan/2014:17:36:58 +0100] [Job 248] Using image rendering resolution 600 dpi
D [28/Jan/2014:17:36:58 +0100] [Job 248] Started filter gs (PID 12519)
D [28/Jan/2014:17:36:58 +0100] [Job 248] Started filter pstops (PID 12520)
D [28/Jan/2014:17:36:58 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 1.1 Get-Notifications 1
D [28/Jan/2014:17:36:58 +0100] Get-Notifications /
D [28/Jan/2014:17:36:58 +0100] cupsdIsAuthorized: requesting-user-name="omula"
D [28/Jan/2014:17:36:58 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [28/Jan/2014:17:36:58 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] Page = 595x842; 12,12 to 583,830
D [28/Jan/2014:17:36:58 +0100] [Job 248] slow_collate=0, slow_duplex=0, slow_order=0
D [28/Jan/2014:17:36:58 +0100] [Job 248] Before copy_comments - %!PS-Adobe-3.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] %!PS-Adobe-3.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] %%BoundingBox: 0 0 595 842
D [28/Jan/2014:17:36:58 +0100] [Job 248] %%Creator: GPL Ghostscript 905 (ps2write)
D [28/Jan/2014:17:36:58 +0100] [Job 248] %%LanguageLevel: 2
D [28/Jan/2014:17:36:58 +0100] [Job 248] %%CreationDate: D:20140128173658+01'00'
D [28/Jan/2014:17:36:58 +0100] [Job 248] %%Pages: 4
D [28/Jan/2014:17:36:58 +0100] [Job 248] %%EndComments
D [28/Jan/2014:17:36:58 +0100] [Job 248] Before copy_prolog - %%BeginProlog
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] cupsdAcceptClient: 15 from localhost (Domain)
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:58 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:58 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Classes 1
D [28/Jan/2014:17:36:58 +0100] CUPS-Get-Classes
D [28/Jan/2014:17:36:58 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [28/Jan/2014:17:36:58 +0100] CUPS-Get-Default
D [28/Jan/2014:17:36:58 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Jan/2014:17:36:58 +0100] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] ATTR: marker-colors=#00FFFF,#FF00FF,#FFFF00,#000000,#00FFFF,#00FFFF,#FF00FF,#FF00FF,#FFFF00,#FFFF00,#000000,#000000,none,none,none,none,none
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] ATTR: marker-names='"Toner (Cyan)"','"Toner (Magenta)"','"Toner (Yellow)"','"Toner (Black)"','"Drum Cartridge (Cyan)"','"Developer Cartridge (Cyan)"','"Drum Cartridge (Magenta)"','"Developer Cartridge (Magenta)"','"Drum Cartridge (Yellow)"','"Developer Cartridge (Yellow)"','"Drum Cartridge (Black)"','"Developer Cartridge (Black)"','"Waste Toner Box"','"Fusing Unit"','"Image Transfer Belt Unit"','"Transfer Roller Unit"','"Ozone Filter"'
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] ATTR: marker-types=toner,toner,toner,toner,opc,developer,opc,developer,opc,developer,opc,developer,wasteToner,fuser,transferUnit,transferUnit,cleanerUnit
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] ATTR: marker-levels=5,28,9,97,9,59,9,59,9,59,47,83,-1,81,65,76,76
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] new_supply_state=40, change_state=ffff
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -developer-low-report
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -developer-empty-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -marker-supply-low-report
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -marker-supply-empty-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -opc-near-eol-report
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -opc-life-over-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: +toner-low-report
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -toner-empty-warning
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] new_state=100, change_state=ffff
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -media-low-report
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -media-empty-warning
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -door-open-report
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -media-jam-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -input-tray-missing-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -output-tray-missing-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -marker-supply-missing-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -output-area-almost-full-report
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -output-area-full-warning
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 8192 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Before copy_setup - %%Page: 1 1
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Before page loop - %%Page: 1 1
D [28/Jan/2014:17:36:58 +0100] [Job 248] Copying page 1...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] PAGE: 1 1
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] pagew = 571.0, pagel = 818.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageLeft = 12.0, PageRight = 583.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageTop = 830.0, PageBottom = 12.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageWidth = 595.0, PageLength = 842.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Copying page 2...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] PAGE: 2 1
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 1203 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 1203 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] pagew = 571.0, pagel = 818.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageLeft = 12.0, PageRight = 583.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageTop = 830.0, PageBottom = 12.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageWidth = 595.0, PageLength = 842.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Copying page 3...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 2709 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] PAGE: 3 1
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 2709 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] pagew = 571.0, pagel = 818.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageLeft = 12.0, PageRight = 583.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageTop = 830.0, PageBottom = 12.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageWidth = 595.0, PageLength = 842.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Copying page 4...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4290 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] PAGE: 4 1
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4290 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] pagew = 571.0, pagel = 818.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageLeft = 12.0, PageRight = 583.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageTop = 830.0, PageBottom = 12.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] PageWidth = 595.0, PageLength = 842.0
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4096 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 4 pages...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 2569 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 2569 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Read 55 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Wrote 55 bytes of print data...
D [28/Jan/2014:17:36:58 +0100] [Job 248] PID 12520 (pstops) exited with no errors.
D [28/Jan/2014:17:36:58 +0100] [Job 248] PID 12519 (gs) exited with no errors.
D [28/Jan/2014:17:36:58 +0100] PID 12517 (/usr/lib/cups/filter/pdftops) exited with no errors.
D [28/Jan/2014:17:36:58 +0100] [Job 248] 1 files to send in job...
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: +connecting-to-device
D [28/Jan/2014:17:36:58 +0100] [Job 248] Connecting to 192.168.50.2:631
I [28/Jan/2014:17:36:58 +0100] [Job 248] Connecting to printer...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Set job-printer-state-message to "Connecting to printer...", current level=INFO
D [28/Jan/2014:17:36:58 +0100] [Job 248] 
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] STATE: -connecting-to-device
I [28/Jan/2014:17:36:58 +0100] [Job 248] Connected to printer...
D [28/Jan/2014:17:36:58 +0100] [Job 248] Set job-printer-state-message to "Connected to printer...", current level=INFO
D [28/Jan/2014:17:36:58 +0100] [Job 248] 
D [28/Jan/2014:17:36:58 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] Connected to 192.168.50.2:631 (IPv4)...
D [28/Jan/2014:17:36:58 +0100] [Job 248] ATTR: marker-levels=5,28,9,97,9,59,9,59,9,59,47,82,-1,81,64,76,76
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] new_supply_state=40, change_state=0
D [28/Jan/2014:17:36:58 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:58 +0100] [Job 248] new_state=100, change_state=0
D [28/Jan/2014:17:36:58 +0100] [Job 248] Getting supported attributes...
D [28/Jan/2014:17:36:58 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:58 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:58 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1
D [28/Jan/2014:17:36:58 +0100] Get-Notifications /
D [28/Jan/2014:17:36:58 +0100] cupsdIsAuthorized: requesting-user-name="omula"
D [28/Jan/2014:17:36:58 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jan/2014:17:36:58 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:36:59 +0100] [Job 248] document-format-supported (6 values)
D [28/Jan/2014:17:36:59 +0100] [Job 248] [0] = "text/html"
D [28/Jan/2014:17:36:59 +0100] [Job 248] [1] = "text/plain"
D [28/Jan/2014:17:36:59 +0100] [Job 248] [2] = "application/postscript"
D [28/Jan/2014:17:36:59 +0100] [Job 248] [3] = "application/vnd.hp-PCL"
D [28/Jan/2014:17:36:59 +0100] [Job 248] [4] = "application/pdf"
D [28/Jan/2014:17:36:59 +0100] [Job 248] [5] = "application/octet-stream"
I [28/Jan/2014:17:36:59 +0100] [Job 248] 
D [28/Jan/2014:17:36:59 +0100] [Job 248] Set job-printer-state-message to "Connected to printer...", current level=INFO
D [28/Jan/2014:17:36:59 +0100] [Job 248] STATE: media-empty-warning
D [28/Jan/2014:17:36:59 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] [Job 248] printer-uri = "ipp://192.168.50.2:631/ipp"
D [28/Jan/2014:17:36:59 +0100] [Job 248] requesting-user-name = "omula"
D [28/Jan/2014:17:36:59 +0100] [Job 248] job-name = "linuxchecklist.pdf"
D [28/Jan/2014:17:36:59 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:36:59 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] Avahi client started
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jan/2014:17:36:59 +0100] cupsdCloseClient: 17
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:59 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Classes 1
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Classes
D [28/Jan/2014:17:36:59 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Default
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Jan/2014:17:36:59 +0100] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1
D [28/Jan/2014:17:36:59 +0100] Get-Notifications /
D [28/Jan/2014:17:36:59 +0100] cupsdIsAuthorized: requesting-user-name="omula"
D [28/Jan/2014:17:36:59 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jan/2014:17:36:59 +0100] cupsdCloseClient: 17
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:36:59 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Classes 1
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Classes
D [28/Jan/2014:17:36:59 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:36:59 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:36:59 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Default
D [28/Jan/2014:17:36:59 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Jan/2014:17:36:59 +0100] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Jan/2014:17:36:59 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
N [28/Jan/2014:17:37:00 +0100] [Job 248] Print file accepted - job ID 24628.
D [28/Jan/2014:17:37:00 +0100] [Job 248] Set job-printer-state-message to "Print file accepted - job ID 24628.", current level=INFO
D [28/Jan/2014:17:37:00 +0100] [Job 248] 
I [28/Jan/2014:17:37:00 +0100] [Job 248] Waiting for job to complete...
D [28/Jan/2014:17:37:00 +0100] [Job 248] Set job-printer-state-message to "Waiting for job to complete...", current level=INFO
D [28/Jan/2014:17:37:00 +0100] [Job 248] 
D [28/Jan/2014:17:37:00 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1
D [28/Jan/2014:17:37:00 +0100] Get-Notifications /
D [28/Jan/2014:17:37:00 +0100] cupsdIsAuthorized: requesting-user-name="omula"
D [28/Jan/2014:17:37:00 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jan/2014:17:37:00 +0100] cupsdCloseClient: 17
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [28/Jan/2014:17:37:00 +0100] [Job 248] 
D [28/Jan/2014:17:37:00 +0100] [Job 248] Set job-printer-state-message to "Waiting for job to complete...", current level=INFO
D [28/Jan/2014:17:37:00 +0100] [Job 248] STATE: media-empty-warning
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:37:00 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:37:00 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Classes 1
D [28/Jan/2014:17:37:00 +0100] CUPS-Get-Classes
D [28/Jan/2014:17:37:00 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [28/Jan/2014:17:37:00 +0100] CUPS-Get-Default
D [28/Jan/2014:17:37:00 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Jan/2014:17:37:00 +0100] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jan/2014:17:37:00 +0100] [Job 248] ATTR: marker-levels=5,28,9,97,9,59,9,59,9,59,47,82,-1,81,64,76,76
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] [Job 248] new_supply_state=40, change_state=0
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] [Job 248] new_state=100, change_state=0
D [28/Jan/2014:17:37:00 +0100] [Job 248] ATTR: auth-info-required=none
D [28/Jan/2014:17:37:00 +0100] load_ppd: Loading /var/cache/cups/KONICA-MINOLTA-C360SeriesPS-P.data...
D [28/Jan/2014:17:37:00 +0100] Calling DeleteDevice(cups-KONICA-MINOLTA-C360SeriesPS-P)
D [28/Jan/2014:17:37:00 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [28/Jan/2014:17:37:00 +0100] Using profile id of KONICA-MINOLTA-C360SeriesPS-P-Gray..
D [28/Jan/2014:17:37:00 +0100] Calling CreateProfile(KONICA-MINOLTA-C360SeriesPS-P-Gray..,temp)
W [28/Jan/2014:17:37:00 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'KONICA-MINOLTA-C360SeriesPS-P-Gray..' already exists
D [28/Jan/2014:17:37:00 +0100] Using profile id of KONICA-MINOLTA-C360SeriesPS-P-CMYK..
D [28/Jan/2014:17:37:00 +0100] Calling CreateProfile(KONICA-MINOLTA-C360SeriesPS-P-CMYK..,temp)
W [28/Jan/2014:17:37:00 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'KONICA-MINOLTA-C360SeriesPS-P-CMYK..' already exists
I [28/Jan/2014:17:37:00 +0100] Registering ICC color profiles for "KONICA-MINOLTA-C360SeriesPS-P"
D [28/Jan/2014:17:37:00 +0100] Calling CreateDevice(cups-KONICA-MINOLTA-C360SeriesPS-P,temp)
W [28/Jan/2014:17:37:00 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-KONICA-MINOLTA-C360SeriesPS-P' already exists
D [28/Jan/2014:17:37:00 +0100] cupsdRegisterPrinter(p=0x7f6e387e4040(KONICA-MINOLTA-C360SeriesPS-P))
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(P-----)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [28/Jan/2014:17:37:00 +0100] [Job 248] Ready to print.
D [28/Jan/2014:17:37:00 +0100] [Job 248] Set job-printer-state-message to "Ready to print.", current level=INFO
D [28/Jan/2014:17:37:00 +0100] [Job 248] 
D [28/Jan/2014:17:37:00 +0100] Discarding unused job-progress event...
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] PID 12518 (/usr/lib/cups/backend/ipp) exited with no errors.
D [28/Jan/2014:17:37:00 +0100] Discarding unused job-completed event...
I [28/Jan/2014:17:37:00 +0100] [Job 248] Job completed.
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(----J-)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdMarkDirty(-----S)
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1
D [28/Jan/2014:17:37:00 +0100] Get-Notifications /
D [28/Jan/2014:17:37:00 +0100] cupsdIsAuthorized: requesting-user-name="omula"
D [28/Jan/2014:17:37:00 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:37:00 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [28/Jan/2014:17:37:00 +0100] cupsdCloseClient: 16
D [28/Jan/2014:17:37:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jan/2014:17:37:01 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:37:01 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jan/2014:17:37:01 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:01 +0100] [Job 248] Unloading...
D [28/Jan/2014:17:37:01 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [28/Jan/2014:17:37:01 +0100] CUPS-Get-Printers
D [28/Jan/2014:17:37:01 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jan/2014:17:37:01 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:37:01 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:37:01 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jan/2014:17:37:01 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:01 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Classes 1
D [28/Jan/2014:17:37:01 +0100] CUPS-Get-Classes
D [28/Jan/2014:17:37:01 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jan/2014:17:37:01 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jan/2014:17:37:01 +0100] cupsdReadClient: 15 POST / HTTP/1.1
D [28/Jan/2014:17:37:01 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jan/2014:17:37:01 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jan/2014:17:37:01 +0100] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [28/Jan/2014:17:37:01 +0100] CUPS-Get-Default
D [28/Jan/2014:17:37:01 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Jan/2014:17:37:01 +0100] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Jan/2014:17:37:01 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [28/Jan/2014:17:37:29 +0100] Saving printers.conf...
I [28/Jan/2014:17:37:29 +0100] Saving job.cache...
I [28/Jan/2014:17:37:29 +0100] Saving subscriptions.conf...
D [28/Jan/2014:17:37:30 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [28/Jan/2014:17:42:02 +0100] Closing client 15 after 300 seconds of inactivity...
D [28/Jan/2014:17:42:02 +0100] cupsdCloseClient: 15
D [28/Jan/2014:17:42:02 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2014:17:42:02 +0100] Report: clients=0
D [28/Jan/2014:17:42:02 +0100] Report: jobs=13
D [28/Jan/2014:17:42:02 +0100] Report: jobs-active=0
D [28/Jan/2014:17:42:02 +0100] Report: printers=1
D [28/Jan/2014:17:42:02 +0100] Report: printers-implicit=0
D [28/Jan/2014:17:42:02 +0100] Report: stringpool-string-count=16273
D [28/Jan/2014:17:42:02 +0100] Report: stringpool-alloc-bytes=12536
D [28/Jan/2014:17:42:02 +0100] Report: stringpool-total-bytes=296784

Reply to:

Follow-Ups:
- Bug#736942: cups: Printing not working with Konica Minolta C220
  - From: Brian Potkin <claremont102@gmail.com>

Prev by Date: Bug#712719: cups: CUPS printing broken in Wheezy (Unable to get printer status)
Next by Date: Re: CUPS is now linked against OpenSSL (and will stay GPLv2-only)
Previous by thread: Bug#712719: cups: CUPS printing broken in Wheezy (Unable to get printer status)
Next by thread: Bug#736942: cups: Printing not working with Konica Minolta C220
Index(es):
- Date
- Thread