Bug#736089: cups-client: Modifying or reinstalling a queue with lpadmin is unreliable

To: Debian Bug Tracking System <submit@bugs.debian.org>
Subject: Bug#736089: cups-client: Modifying or reinstalling a queue with lpadmin is unreliable
From: Brian Potkin <claremont102@gmail.com>
Date: Sun, 19 Jan 2014 16:12:27 +0000
Message-id: <[🔎] 17012014181354.f0380d8bb002@desktop.copernicus.demon.co.uk>
Reply-to: Brian Potkin <claremont102@gmail.com>, 736089@bugs.debian.org

Package: cups-client
Version: 1.7.1-2
Severity: important



1. A print queue was created on an up-to-date Sid machine with

     lpadmin -p test -v file:/dev/null -m drv:///hpijs.drv/hp-deskjet_f300_series-hpijs.ppd

2. A request to modify the queue was issued with

     lpadmin -p test -v file:/dev/null -m drv:///hpcups.drv/hp-deskjet_f300_series.ppd

   A check of the contents of /etc/cupps/ppd/test revealed that the PPD
   had been changed.

3. A second request to modify the queue was made with

     lpadmin -p test -v file:/dev/null -m drv:///hpijs.drv/hp-deskjet_f300_series-hpijs.ppd

   This time /etc/cupps/ppd/test was unchanged.

4. Repeating 3. resulted in a change in /etc/cupps/ppd/test.

An error log is attached. Portions of the log for queue installation and
successful modification have 

  D [19/Jan/2014:15:17:09 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
  D [19/Jan/2014:15:17:09 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
  D [19/Jan/2014:15:17:09 +0000] cupsdIsAuthorized: username="root"
  D [19/Jan/2014:15:17:09 +0000] test device-uri: OK
  I [19/Jan/2014:15:17:09 +0000] Setting test device-uri to "file:/dev/null" (was "file:///dev/null".)
  D [19/Jan/2014:15:17:09 +0000] copy_model: Running "cups-driverd cat drv:///hpijs.drv/hp-deskjet_f300_series-hpijs.ppd"...
  D [19/Jan/2014:15:17:10 +0000] Copied PPD file successfully

or

  D [19/Jan/2014:15:17:34 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
  D [19/Jan/2014:15:17:34 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
  D [19/Jan/2014:15:17:34 +0000] cupsdIsAuthorized: username="root"
  D [19/Jan/2014:15:17:34 +0000] test device-uri: OK
  I [19/Jan/2014:15:17:34 +0000] Setting test device-uri to "file:/dev/null" (was "file:/dev/null".)
  D [19/Jan/2014:15:17:34 +0000] copy_model: Running "cups-driverd cat drv:///hpcups.drv/hp-deskjet_f300_series.ppd"...
  D [19/Jan/2014:15:17:34 +0000] Copied PPD file successfully

For an unsucessful modification we have

  D [19/Jan/2014:15:18:00 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
  D [19/Jan/2014:15:18:00 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
  D [19/Jan/2014:15:18:00 +0000] cupsdIsAuthorized: username="root"
  D [19/Jan/2014:15:18:00 +0000] test device-uri: OK
  I [19/Jan/2014:15:18:00 +0000] Setting test device-uri to "file:/dev/null" (was "file:/dev/null".)
  D [19/Jan/2014:15:18:00 +0000] Copied PPD file successfully

where we see cups-driverd has not been run.


Further observations.
---------------------

(a) Reinstalling a queue is also only successful every second try.

(b) The described behaviour is exhibited on Wheezy.

(c) With a Fedora Live image (CUPS 1.7.0) lpadmin behaves as expected.

(d) On Squeeze lpadmin also behaves as expected.

(e) The ppd_updater function in cups.postinst uses lpadmin.

Regards,

Brian.

I [19/Jan/2014:15:17:01 +0000] Saving printers.conf...
I [19/Jan/2014:15:17:01 +0000] Generating printcap /var/run/cups/printcap...
D [19/Jan/2014:15:17:01 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [19/Jan/2014:15:17:01 +0000] Saving job.cache...
D [19/Jan/2014:15:17:01 +0000] cupsdStopSelect()
E [19/Jan/2014:15:17:01 +0000] Unknown directive BrowseAddress on line 8 of /etc/cups/cupsd.conf.
I [19/Jan/2014:15:17:01 +0000] Please move "FileDevice yes" on line 86 of /etc/cups/cupsd.conf to the /etc/cups/cups-files.conf file; this will become an error in a future release.
I [19/Jan/2014:15:17:01 +0000] Remote access is enabled.
D [19/Jan/2014:15:17:01 +0000] Added auto ServerAlias desktop3
I [19/Jan/2014:15:17:01 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [19/Jan/2014:15:17:01 +0000] Using default TempDir of /var/spool/cups/tmp...
I [19/Jan/2014:15:17:01 +0000] Configured for up to 100 clients.
I [19/Jan/2014:15:17:01 +0000] Allowing up to 100 client connections per host.
I [19/Jan/2014:15:17:01 +0000] Using policy "default" as the default.
I [19/Jan/2014:15:17:01 +0000] Full reload is required.
I [19/Jan/2014:15:17:01 +0000] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 39 types, 53 filters...
D [19/Jan/2014:15:17:01 +0000] Loading printer CUPS-PDF...
D [19/Jan/2014:15:17:01 +0000] load_ppd: Loading /var/cache/cups/CUPS-PDF.data...
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb859db90(CUPS-PDF))
D [19/Jan/2014:15:17:01 +0000] Loading printer CUPSout...
D [19/Jan/2014:15:17:01 +0000] load_ppd: Loading /var/cache/cups/CUPSout.data...
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85a16c0(CUPSout))
D [19/Jan/2014:15:17:01 +0000] Loading printer PDF...
D [19/Jan/2014:15:17:01 +0000] load_ppd: Loading /var/cache/cups/PDF.data...
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85b0e40(PDF))
D [19/Jan/2014:15:17:01 +0000] Loading printer PDFprinter...
D [19/Jan/2014:15:17:01 +0000] load_ppd: Loading /var/cache/cups/PDFprinter.data...
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85b60e0(PDFprinter))
D [19/Jan/2014:15:17:01 +0000] Loading printer testprinter...
D [19/Jan/2014:15:17:01 +0000] load_ppd: Loading /var/cache/cups/testprinter.data...
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85d9958(testprinter))
D [19/Jan/2014:15:17:01 +0000] Loading printer XXXX...
D [19/Jan/2014:15:17:01 +0000] load_ppd: Loading /var/cache/cups/XXXX.data...
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85dd6c8(XXXX))
I [19/Jan/2014:15:17:01 +0000] Loading job cache file "/var/cache/cups/job.cache"...
D [19/Jan/2014:15:17:01 +0000] [Job 1131] Loading from cache...
D [19/Jan/2014:15:17:01 +0000] [Job 1132] Loading from cache...
D [19/Jan/2014:15:17:01 +0000] [Job 1133] Loading from cache...
I [19/Jan/2014:15:17:01 +0000] Full reload complete.
D [19/Jan/2014:15:17:01 +0000] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [19/Jan/2014:15:17:01 +0000] Cleaning out old files in "/var/spool/cups/tmp".
D [19/Jan/2014:15:17:01 +0000] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [19/Jan/2014:15:17:01 +0000] Cleaning out old files in "/var/cache/cups".
D [19/Jan/2014:15:17:01 +0000] Calling FindDeviceById(cups-CUPS-PDF)
D [19/Jan/2014:15:17:01 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "CUPS-PDF-Gray..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(CUPS-PDF-Gray..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "CUPS-PDF-RGB..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(CUPS-PDF-RGB..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:01 +0000] Registering ICC color profiles for "CUPS-PDF".
D [19/Jan/2014:15:17:01 +0000] Calling CreateDevice(cups-CUPS-PDF,temp)
W [19/Jan/2014:15:17:01 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Calling FindDeviceById(cups-CUPSout)
D [19/Jan/2014:15:17:01 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "CUPSout-Gray..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(CUPSout-Gray..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "CUPSout-CMYK..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(CUPSout-CMYK..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:01 +0000] Registering ICC color profiles for "CUPSout".
D [19/Jan/2014:15:17:01 +0000] Calling CreateDevice(cups-CUPSout,temp)
W [19/Jan/2014:15:17:01 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Calling FindDeviceById(cups-PDF)
D [19/Jan/2014:15:17:01 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "PDF-Gray..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(PDF-Gray..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "PDF-RGB..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(PDF-RGB..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:01 +0000] Registering ICC color profiles for "PDF".
D [19/Jan/2014:15:17:01 +0000] Calling CreateDevice(cups-PDF,temp)
W [19/Jan/2014:15:17:01 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Calling FindDeviceById(cups-PDFprinter)
D [19/Jan/2014:15:17:01 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "PDFprinter-Gray..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(PDFprinter-Gray..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "PDFprinter-CMYK..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(PDFprinter-CMYK..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:01 +0000] Registering ICC color profiles for "PDFprinter".
D [19/Jan/2014:15:17:01 +0000] Calling CreateDevice(cups-PDFprinter,temp)
W [19/Jan/2014:15:17:01 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Calling FindDeviceById(cups-testprinter)
D [19/Jan/2014:15:17:01 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "testprinter-Gray..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(testprinter-Gray..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:01 +0000] Registering ICC color profiles for "testprinter".
D [19/Jan/2014:15:17:01 +0000] Calling CreateDevice(cups-testprinter,temp)
W [19/Jan/2014:15:17:01 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Calling FindDeviceById(cups-XXXX)
D [19/Jan/2014:15:17:01 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:01 +0000] Using profile ID "XXXX-Gray..".
D [19/Jan/2014:15:17:01 +0000] Calling CreateProfile(XXXX-Gray..,temp)
W [19/Jan/2014:15:17:01 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:01 +0000] Registering ICC color profiles for "XXXX".
D [19/Jan/2014:15:17:01 +0000] Calling CreateDevice(cups-XXXX,temp)
W [19/Jan/2014:15:17:01 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:01 +0000] Listening to 0.0.0.0:631 on fd 10...
I [19/Jan/2014:15:17:01 +0000] Listening to [v1.::]:631 on fd 11...
I [19/Jan/2014:15:17:01 +0000] Listening to /var/run/cups/cups.sock:631 on fd 12...
I [19/Jan/2014:15:17:01 +0000] Resuming new connection processing...
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb859db90(CUPS-PDF))
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85a16c0(CUPSout))
D [19/Jan/2014:15:17:01 +0000] Registering "CUPSout @ desktop3" with DNS-SD type "_printer._tcp".
D [19/Jan/2014:15:17:01 +0000] Registering "CUPSout @ desktop3" with DNS-SD type "_ipps._tcp".
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" printer-type=0x101E
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" printer-state=5
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" Duplex=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" Color=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" UUID=4631a430-6b90-3dfa-6708-b7c3e7fc678c
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" URF=DM3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" product=(Generic PDF Printer)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" priority=0
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" adminurl=https://desktop3.local:631/printers/CUPSout
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" ty=Generic PDF Printer
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" rp=printers/CUPSout
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" qtotal=1
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" txtvers=1
D [19/Jan/2014:15:17:01 +0000] Registering "CUPSout @ desktop3" with DNS-SD type "_ipp._tcp".
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" printer-type=0x101E
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" printer-state=5
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" Duplex=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" Color=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" UUID=4631a430-6b90-3dfa-6708-b7c3e7fc678c
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" URF=DM3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" product=(Generic PDF Printer)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" priority=0
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" adminurl=https://desktop3.local:631/printers/CUPSout
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" ty=Generic PDF Printer
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" rp=printers/CUPSout
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" qtotal=1
D [19/Jan/2014:15:17:01 +0000] DNS_SD "CUPSout @ desktop3" txtvers=1
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85b0e40(PDF))
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85b60e0(PDFprinter))
D [19/Jan/2014:15:17:01 +0000] Registering "Print to PDF @ desktop3" with DNS-SD type "_printer._tcp".
D [19/Jan/2014:15:17:01 +0000] Registering "Print to PDF @ desktop3" with DNS-SD type "_ipps._tcp".
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" printer-type=0x101E
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" printer-state=5
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" Duplex=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" Color=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" UUID=9e39483d-54b7-30fb-43b3-1de19b4f48f6
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" URF=DM3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" product=(Generic PDF Printer)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" priority=0
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" note=Emailed
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" adminurl=https://desktop3.local:631/printers/PDFprinter
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" ty=Generic PDF Printer
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" rp=printers/PDFprinter
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" qtotal=1
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" txtvers=1
D [19/Jan/2014:15:17:01 +0000] Registering "Print to PDF @ desktop3" with DNS-SD type "_ipp._tcp".
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" printer-type=0x101E
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" printer-state=5
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" Duplex=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" Color=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" UUID=9e39483d-54b7-30fb-43b3-1de19b4f48f6
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" URF=DM3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" product=(Generic PDF Printer)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" priority=0
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" note=Emailed
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" adminurl=https://desktop3.local:631/printers/PDFprinter
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" ty=Generic PDF Printer
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" rp=printers/PDFprinter
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" qtotal=1
D [19/Jan/2014:15:17:01 +0000] DNS_SD "Print to PDF @ desktop3" txtvers=1
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85d9958(testprinter))
D [19/Jan/2014:15:17:01 +0000] cupsdRegisterPrinter(p=0xb85dd6c8(XXXX))
D [19/Jan/2014:15:17:01 +0000] Registering "XXXX @ desktop3" with DNS-SD type "_printer._tcp".
D [19/Jan/2014:15:17:01 +0000] Registering "XXXX @ desktop3" with DNS-SD type "_ipps._tcp".
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" printer-type=0x809056
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" printer-state=3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" Copies=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" Duplex=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" UUID=dea264df-f1c2-3eff-775b-d5efdd732712
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" URF=DM3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" product=(FS-1300D)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" priority=0
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" adminurl=https://desktop3.local:631/printers/XXXX
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" ty=Kyocera FS-1300D (KPDL)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" rp=printers/XXXX
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" qtotal=1
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" txtvers=1
D [19/Jan/2014:15:17:01 +0000] Registering "XXXX @ desktop3" with DNS-SD type "_ipp._tcp".
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" printer-type=0x809056
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" printer-state=3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" Copies=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" Duplex=T
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" UUID=dea264df-f1c2-3eff-775b-d5efdd732712
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" URF=DM3
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" product=(FS-1300D)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" priority=0
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" adminurl=https://desktop3.local:631/printers/XXXX
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" ty=Kyocera FS-1300D (KPDL)
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" rp=printers/XXXX
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" qtotal=1
D [19/Jan/2014:15:17:01 +0000] DNS_SD "XXXX @ desktop3" txtvers=1
D [19/Jan/2014:15:17:01 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [19/Jan/2014:15:17:01 +0000] Discarding unused server-started event...
D [19/Jan/2014:15:17:02 +0000] Report: clients=0
D [19/Jan/2014:15:17:02 +0000] Report: jobs=3
D [19/Jan/2014:15:17:02 +0000] Report: jobs-active=0
D [19/Jan/2014:15:17:02 +0000] Report: printers=6
D [19/Jan/2014:15:17:02 +0000] Report: stringpool-string-count=20633
D [19/Jan/2014:15:17:02 +0000] Report: stringpool-alloc-bytes=16008
D [19/Jan/2014:15:17:02 +0000] Report: stringpool-total-bytes=378736
D [19/Jan/2014:15:17:09 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:09 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:09 +0000] [Client 16] POST / HTTP/1.1
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Jan/2014:15:17:09 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:17:09 +0000] [Client 16] 2.0 Get-Printer-Attributes 1
D [19/Jan/2014:15:17:09 +0000] Get-Printer-Attributes ipp://localhost:631/printers/test
D [19/Jan/2014:15:17:09 +0000] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [19/Jan/2014:15:17:09 +0000] [Client 16] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:17:09 +0000] [Client 16] Content-Length: 130
D [19/Jan/2014:15:17:09 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0xb86c1270(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:17:09 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:17:09 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:17:09 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [19/Jan/2014:15:17:09 +0000] [Client 17] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:09 +0000] [Client 17] Waiting for request.
D [19/Jan/2014:15:17:09 +0000] [Client 17] POST /printers/test HTTP/1.1
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Jan/2014:15:17:09 +0000] [Client 17] No authentication data provided.
D [19/Jan/2014:15:17:09 +0000] [Client 17] 2.0 Get-Printer-Attributes 3
D [19/Jan/2014:15:17:09 +0000] Get-Printer-Attributes ipp://localhost/printers/test
D [19/Jan/2014:15:17:09 +0000] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [19/Jan/2014:15:17:09 +0000] [Client 17] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost/printers/test) from localhost
D [19/Jan/2014:15:17:09 +0000] [Client 17] Content-Length: 130
D [19/Jan/2014:15:17:09 +0000] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0xb86c1270(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:17:09 +0000] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:17:09 +0000] [Client 17] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:17:09 +0000] [Client 17] Waiting for request.
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [19/Jan/2014:15:17:09 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Jan/2014:15:17:09 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:17:09 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:17:09 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:17:09 +0000] cupsdIsAuthorized: username=""
D [19/Jan/2014:15:17:09 +0000] [Client 16] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:17:09 +0000] [Client 16] WWW-Authenticate: Basic realm="CUPS", trc="y"
D [19/Jan/2014:15:17:09 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:09 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [19/Jan/2014:15:17:09 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:09 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:09 +0000] [Client 18] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:09 +0000] [Client 18] Waiting for request.
D [19/Jan/2014:15:17:09 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:09 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [19/Jan/2014:15:17:09 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:09 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:09 +0000] [Client 18] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:09 +0000] [Client 18] Closing connection.
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [19/Jan/2014:15:17:09 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:17:09 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Jan/2014:15:17:09 +0000] [Client 16] Authorized as root using PeerCred
D [19/Jan/2014:15:17:09 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:17:09 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:17:09 +0000] cupsdIsAuthorized: username="root"
D [19/Jan/2014:15:17:09 +0000] test device-uri: OK
I [19/Jan/2014:15:17:09 +0000] Setting test device-uri to "file:/dev/null" (was "file:///dev/null".)
D [19/Jan/2014:15:17:09 +0000] copy_model: Running "cups-driverd cat drv:///hpijs.drv/hp-deskjet_f300_series-hpijs.ppd"...
D [19/Jan/2014:15:17:10 +0000] Copied PPD file successfully
D [19/Jan/2014:15:17:10 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:17:10 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Jan/2014:15:17:10 +0000] Calling FindDeviceById(cups-test)
D [19/Jan/2014:15:17:10 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:10 +0000] Using profile ID "test-Gray..".
D [19/Jan/2014:15:17:10 +0000] Calling CreateProfile(test-Gray..,temp)
W [19/Jan/2014:15:17:10 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:10 +0000] Using profile ID "test-RGB..".
D [19/Jan/2014:15:17:10 +0000] Calling CreateProfile(test-RGB..,temp)
W [19/Jan/2014:15:17:10 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:10 +0000] Registering ICC color profiles for "test".
D [19/Jan/2014:15:17:10 +0000] Calling CreateDevice(cups-test,temp)
W [19/Jan/2014:15:17:10 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:10 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:17:10 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:10 +0000] load_ppd: Loading /etc/cups/ppd/test.ppd...
D [19/Jan/2014:15:17:10 +0000] load_ppd: Saving /var/cache/cups/test.data...
D [19/Jan/2014:15:17:10 +0000] cupsdRegisterPrinter(p=0xb86b7010(test))
D [19/Jan/2014:15:17:10 +0000] Registering "test @ desktop3" with DNS-SD type "_printer._tcp".
D [19/Jan/2014:15:17:10 +0000] Registering "test @ desktop3" with DNS-SD type "_ipps._tcp".
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" printer-type=0x80900E
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series hpijs, 3.14.1
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:17:10 +0000] Registering "test @ desktop3" with DNS-SD type "_ipp._tcp".
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" printer-type=0x80900E
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series hpijs, 3.14.1
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:17:10 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:17:10 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:17:10 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:10 +0000] cupsdMarkDirty(--p--)
D [19/Jan/2014:15:17:10 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:10 +0000] Discarding unused printer-added event...
I [19/Jan/2014:15:17:10 +0000] New printer "test" added by "root".
D [19/Jan/2014:15:17:10 +0000] [Client 16] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:17:10 +0000] [Client 16] Content-Length: 75
D [19/Jan/2014:15:17:10 +0000] PID 6678 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [19/Jan/2014:15:17:10 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xb86aa0d0(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:17:10 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:17:10 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:17:10 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:10 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:10 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:10 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:17:10 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Jan/2014:15:17:10 +0000] [Client 17] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:10 +0000] [Client 17] Closing connection.
D [19/Jan/2014:15:17:10 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:34 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:34 +0000] [Client 16] POST / HTTP/1.1
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:17:34 +0000] [Client 16] 2.0 Get-Printer-Attributes 1
D [19/Jan/2014:15:17:34 +0000] Get-Printer-Attributes ipp://localhost:631/printers/test
D [19/Jan/2014:15:17:34 +0000] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:17:34 +0000] [Client 16] Content-Length: 97
D [19/Jan/2014:15:17:34 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=97, response=0xb864f458(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:17:34 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:17:34 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:17:34 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:17:34 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:17:34 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:17:34 +0000] cupsdIsAuthorized: username=""
D [19/Jan/2014:15:17:34 +0000] [Client 16] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:17:34 +0000] [Client 16] WWW-Authenticate: Basic realm="CUPS", trc="y"
D [19/Jan/2014:15:17:34 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:34 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:34 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:34 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:34 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:34 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:34 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:34 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:17:34 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:34 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] Authorized as root using PeerCred
D [19/Jan/2014:15:17:34 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:17:34 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:17:34 +0000] cupsdIsAuthorized: username="root"
D [19/Jan/2014:15:17:34 +0000] test device-uri: OK
I [19/Jan/2014:15:17:34 +0000] Setting test device-uri to "file:/dev/null" (was "file:/dev/null".)
D [19/Jan/2014:15:17:34 +0000] copy_model: Running "cups-driverd cat drv:///hpcups.drv/hp-deskjet_f300_series.ppd"...
D [19/Jan/2014:15:17:34 +0000] Copied PPD file successfully
D [19/Jan/2014:15:17:34 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:34 +0000] Calling FindDeviceById(cups-test)
D [19/Jan/2014:15:17:34 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:34 +0000] Using profile ID "test-Gray..".
D [19/Jan/2014:15:17:34 +0000] Calling CreateProfile(test-Gray..,temp)
W [19/Jan/2014:15:17:34 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:34 +0000] Using profile ID "test-RGB..".
D [19/Jan/2014:15:17:34 +0000] Calling CreateProfile(test-RGB..,temp)
W [19/Jan/2014:15:17:34 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:17:34 +0000] Registering ICC color profiles for "test".
D [19/Jan/2014:15:17:34 +0000] Calling CreateDevice(cups-test,temp)
W [19/Jan/2014:15:17:34 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:17:34 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:34 +0000] load_ppd: Loading /etc/cups/ppd/test.ppd...
D [19/Jan/2014:15:17:34 +0000] load_ppd: Saving /var/cache/cups/test.data...
D [19/Jan/2014:15:17:34 +0000] cupsdRegisterPrinter(p=0xb86b7010(test))
D [19/Jan/2014:15:17:34 +0000] Registering "test @ desktop3" with DNS-SD type "_printer._tcp".
D [19/Jan/2014:15:17:34 +0000] Registering "test @ desktop3" with DNS-SD type "_ipps._tcp".
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" printer-type=0x80901E
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" Duplex=T
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series, hpcups 3.14.1
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:17:34 +0000] Registering "test @ desktop3" with DNS-SD type "_ipp._tcp".
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" printer-type=0x80901E
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" Duplex=T
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series, hpcups 3.14.1
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:17:34 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:17:34 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:34 +0000] cupsdMarkDirty(--p--)
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:34 +0000] Discarding unused printer-modified event...
I [19/Jan/2014:15:17:34 +0000] Printer "test" modified by "root".
D [19/Jan/2014:15:17:34 +0000] [Client 16] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:17:34 +0000] [Client 16] Content-Length: 75
D [19/Jan/2014:15:17:34 +0000] PID 6681 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [19/Jan/2014:15:17:34 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xb86b46e0(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:17:34 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:17:34 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:17:34 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:17:34 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:17:34 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:17:34 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Jan/2014:15:17:41 +0000] Saving printers.conf...
I [19/Jan/2014:15:17:41 +0000] Generating printcap /var/run/cups/printcap...
D [19/Jan/2014:15:17:41 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [19/Jan/2014:15:18:00 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:00 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:00 +0000] [Client 16] POST / HTTP/1.1
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Jan/2014:15:18:00 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:18:00 +0000] [Client 16] 2.0 Get-Printer-Attributes 1
D [19/Jan/2014:15:18:00 +0000] Get-Printer-Attributes ipp://localhost:631/printers/test
D [19/Jan/2014:15:18:00 +0000] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:18:00 +0000] [Client 16] Content-Length: 97
D [19/Jan/2014:15:18:00 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=97, response=0xb86b3ba8(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:18:00 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:18:00 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:18:00 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [19/Jan/2014:15:18:00 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Jan/2014:15:18:00 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:18:00 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:18:00 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:18:00 +0000] cupsdIsAuthorized: username=""
D [19/Jan/2014:15:18:00 +0000] [Client 16] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:18:00 +0000] [Client 16] WWW-Authenticate: Basic realm="CUPS", trc="y"
D [19/Jan/2014:15:18:00 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:00 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [19/Jan/2014:15:18:00 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:00 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:00 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:00 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [19/Jan/2014:15:18:00 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:00 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:00 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:00 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [19/Jan/2014:15:18:00 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:00 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:00 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [19/Jan/2014:15:18:00 +0000] [Client 16] Authorized as root using PeerCred
D [19/Jan/2014:15:18:00 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:18:00 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:18:00 +0000] cupsdIsAuthorized: username="root"
D [19/Jan/2014:15:18:00 +0000] test device-uri: OK
I [19/Jan/2014:15:18:00 +0000] Setting test device-uri to "file:/dev/null" (was "file:/dev/null".)
D [19/Jan/2014:15:18:00 +0000] Copied PPD file successfully
D [19/Jan/2014:15:18:00 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [19/Jan/2014:15:18:00 +0000] Calling FindDeviceById(cups-test)
D [19/Jan/2014:15:18:00 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:18:00 +0000] Using profile ID "test-Gray..".
D [19/Jan/2014:15:18:00 +0000] Calling CreateProfile(test-Gray..,temp)
W [19/Jan/2014:15:18:00 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:18:00 +0000] Using profile ID "test-RGB..".
D [19/Jan/2014:15:18:00 +0000] Calling CreateProfile(test-RGB..,temp)
W [19/Jan/2014:15:18:00 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:18:00 +0000] Registering ICC color profiles for "test".
D [19/Jan/2014:15:18:00 +0000] Calling CreateDevice(cups-test,temp)
W [19/Jan/2014:15:18:00 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:18:00 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:00 +0000] load_ppd: Loading /etc/cups/ppd/test.ppd...
D [19/Jan/2014:15:18:00 +0000] load_ppd: Saving /var/cache/cups/test.data...
D [19/Jan/2014:15:18:00 +0000] cupsdRegisterPrinter(p=0xb86b7010(test))
D [19/Jan/2014:15:18:00 +0000] Registering "test @ desktop3" with DNS-SD type "_printer._tcp".
D [19/Jan/2014:15:18:00 +0000] Registering "test @ desktop3" with DNS-SD type "_ipps._tcp".
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" printer-type=0x80901E
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" Duplex=T
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series, hpcups 3.14.1
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:18:00 +0000] Registering "test @ desktop3" with DNS-SD type "_ipp._tcp".
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" printer-type=0x80901E
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" Duplex=T
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series, hpcups 3.14.1
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:18:00 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:18:00 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:00 +0000] cupsdMarkDirty(--p--)
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:00 +0000] Discarding unused printer-modified event...
I [19/Jan/2014:15:18:00 +0000] Printer "test" modified by "root".
D [19/Jan/2014:15:18:00 +0000] [Client 16] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:18:00 +0000] [Client 16] Content-Length: 75
D [19/Jan/2014:15:18:00 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xb86b3ba8(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:18:00 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:18:00 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:18:00 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:00 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:00 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:00 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Jan/2014:15:18:02 +0000] Report: clients=0
D [19/Jan/2014:15:18:02 +0000] Report: jobs=3
D [19/Jan/2014:15:18:02 +0000] Report: jobs-active=0
D [19/Jan/2014:15:18:02 +0000] Report: printers=7
D [19/Jan/2014:15:18:02 +0000] Report: stringpool-string-count=25994
D [19/Jan/2014:15:18:02 +0000] Report: stringpool-alloc-bytes=25272
D [19/Jan/2014:15:18:02 +0000] Report: stringpool-total-bytes=485240
D [19/Jan/2014:15:18:15 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:15 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:15 +0000] [Client 16] POST / HTTP/1.1
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:18:15 +0000] [Client 16] 2.0 Get-Printer-Attributes 1
D [19/Jan/2014:15:18:15 +0000] Get-Printer-Attributes ipp://localhost:631/printers/test
D [19/Jan/2014:15:18:15 +0000] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:18:15 +0000] [Client 16] Content-Length: 97
D [19/Jan/2014:15:18:15 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=97, response=0xb86b3ba8(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:18:15 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:18:15 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:18:15 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] No authentication data provided.
D [19/Jan/2014:15:18:15 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:18:15 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:18:15 +0000] cupsdIsAuthorized: username=""
D [19/Jan/2014:15:18:15 +0000] [Client 16] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:18:15 +0000] [Client 16] WWW-Authenticate: Basic realm="CUPS", trc="y"
D [19/Jan/2014:15:18:15 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:15 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:15 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:15 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:15 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:15 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:15 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:15 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] Accepted from localhost (Domain)
D [19/Jan/2014:15:18:15 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:15 +0000] [Client 16] POST /admin/ HTTP/1.1
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] Authorized as root using PeerCred
D [19/Jan/2014:15:18:15 +0000] [Client 16] 2.0 CUPS-Add-Modify-Printer 2
D [19/Jan/2014:15:18:15 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/test
D [19/Jan/2014:15:18:15 +0000] cupsdIsAuthorized: username="root"
D [19/Jan/2014:15:18:15 +0000] test device-uri: OK
I [19/Jan/2014:15:18:15 +0000] Setting test device-uri to "file:/dev/null" (was "file:/dev/null".)
D [19/Jan/2014:15:18:15 +0000] copy_model: Running "cups-driverd cat drv:///hpijs.drv/hp-deskjet_f300_series-hpijs.ppd"...
D [19/Jan/2014:15:18:15 +0000] Copied PPD file successfully
D [19/Jan/2014:15:18:15 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:15 +0000] Calling FindDeviceById(cups-test)
D [19/Jan/2014:15:18:15 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:18:15 +0000] Using profile ID "test-Gray..".
D [19/Jan/2014:15:18:15 +0000] Calling CreateProfile(test-Gray..,temp)
W [19/Jan/2014:15:18:15 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:18:15 +0000] Using profile ID "test-RGB..".
D [19/Jan/2014:15:18:15 +0000] Calling CreateProfile(test-RGB..,temp)
W [19/Jan/2014:15:18:15 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [19/Jan/2014:15:18:15 +0000] Registering ICC color profiles for "test".
D [19/Jan/2014:15:18:15 +0000] Calling CreateDevice(cups-test,temp)
W [19/Jan/2014:15:18:15 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [19/Jan/2014:15:18:15 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:15 +0000] load_ppd: Loading /etc/cups/ppd/test.ppd...
D [19/Jan/2014:15:18:15 +0000] load_ppd: Saving /var/cache/cups/test.data...
D [19/Jan/2014:15:18:15 +0000] cupsdRegisterPrinter(p=0xb86b7010(test))
D [19/Jan/2014:15:18:15 +0000] Registering "test @ desktop3" with DNS-SD type "_printer._tcp".
D [19/Jan/2014:15:18:15 +0000] Registering "test @ desktop3" with DNS-SD type "_ipps._tcp".
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" printer-type=0x80900E
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series hpijs, 3.14.1
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:18:15 +0000] Registering "test @ desktop3" with DNS-SD type "_ipp._tcp".
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" printer-type=0x80900E
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" printer-state=5
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" Color=T
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" TLS=1.2
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" UUID=afb48dc6-f981-3e92-58f7-f358efae06f6
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" URF=DM3
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/urf
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" product=(HP Deskjet f394 All-in-one Printer)
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" priority=0
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" adminurl=https://desktop3.local:631/printers/test
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" ty=HP Deskjet f300 Series hpijs, 3.14.1
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" rp=printers/test
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" qtotal=1
D [19/Jan/2014:15:18:15 +0000] DNS_SD "test @ desktop3" txtvers=1
D [19/Jan/2014:15:18:15 +0000] cupsdMarkDirty(P----)
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:15 +0000] cupsdMarkDirty(--p--)
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:15 +0000] Discarding unused printer-modified event...
I [19/Jan/2014:15:18:15 +0000] Printer "test" modified by "root".
D [19/Jan/2014:15:18:15 +0000] [Client 16] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/test) from localhost
D [19/Jan/2014:15:18:15 +0000] [Client 16] Content-Length: 75
D [19/Jan/2014:15:18:15 +0000] PID 6686 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [19/Jan/2014:15:18:15 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xb86becb0(IPP_IDLE), pipe_pid=0, file=-1
D [19/Jan/2014:15:18:15 +0000] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [19/Jan/2014:15:18:15 +0000] [Client 16] bytes=0, http_state=0, data_remaining=0
D [19/Jan/2014:15:18:15 +0000] [Client 16] Waiting for request.
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [19/Jan/2014:15:18:15 +0000] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [19/Jan/2014:15:18:15 +0000] [Client 16] Closing connection.
D [19/Jan/2014:15:18:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [19/Jan/2014:15:18:31 +0000] Saving printers.conf...
I [19/Jan/2014:15:18:31 +0000] Generating printcap /var/run/cups/printcap...
D [19/Jan/2014:15:18:31 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"

Reply to:

Follow-Ups:
- Bug#736089: cups-client: Modifying or reinstalling a queue with lpadmin is unreliable
  - From: Brian Potkin <claremont102@gmail.com>

Prev by Date: Re: cups-filters 1.0.44 and binary package splitting for mobile
Next by Date: Bug#686653: HP Deskjet broken in Wheezy
Previous by thread: Accepted hplip 3.14.1-1 (source i386 all)
Next by thread: Bug#736089: cups-client: Modifying or reinstalling a queue with lpadmin is unreliable
Index(es):
- Date
- Thread