I [14/Aug/2014:13:17:55 +0000] Please move "SystemGroup lpadmin sys root" on line 13 of /etc/cups/cupsd.conf to the /etc/cups/cups-files.conf file; this will become an error in a future release.
I [14/Aug/2014:13:17:55 +0000] Listening to [v1.::1]:631 (IPv6)
I [14/Aug/2014:13:17:55 +0000] Listening to 127.0.0.1:631 (IPv4)
I [14/Aug/2014:13:17:55 +0000] Listening to /var/run/cups/cups.sock (Domain)
E [14/Aug/2014:13:17:55 +0000] Unknown directive BrowseOrder on line 22 of /etc/cups/cupsd.conf.
E [14/Aug/2014:13:17:55 +0000] Unknown directive BrowseAllow on line 23 of /etc/cups/cupsd.conf.
d [14/Aug/2014:13:17:55 +0000] cupsdAddLocation: Added location "/"
d [14/Aug/2014:13:17:55 +0000] cupsdAddLocation: Added location "/admin"
d [14/Aug/2014:13:17:55 +0000] cupsdAddLocation: Added location "/admin/conf"
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fa5f0, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=(nil), op=5(Create-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb588, op=2(Print-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb588, op=3(Print-URI))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb588, op=4(Validate-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=(nil), op=6(Send-Document))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fb6b0, name="@OWNER")
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fb6b0, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=7(Send-URI))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=c(Hold-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=d(Release-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=e(Restart-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=12(Purge-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=14(Set-Job-Attributes))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=17(Create-Job-Subscriptions))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=1a(Renew-Subscription))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=1b(Cancel-Subscription))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=1c(Get-Notifications))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=2c(Reprocess-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=2d(Cancel-Current-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=2e(Suspend-Current-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=2f(Resume-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=39(Cancel-My-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=3b(Close-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=400d(CUPS-Move-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fb6b0, op=4027(CUPS-Get-Document))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=(nil), op=4003(CUPS-Add-Modify-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fcac0, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fcac0, op=4004(CUPS-Delete-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fcac0, op=4006(CUPS-Add-Modify-Class))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fcac0, op=4007(CUPS-Delete-Class))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fcac0, op=400a(CUPS-Set-Default))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fcac0, op=400b(CUPS-Get-Devices))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=(nil), op=10(Pause-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fd128, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=11(Resume-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=22(Enable-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=23(Disable-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=24(Pause-Printer-After-Current-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=25(Hold-New-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=26(Release-Held-New-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=27(Deactivate-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=28(Activate-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=29(Restart-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=2a(Shutdown-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=2b(Startup-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=30(Promote-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=31(Schedule-Job-After))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=38(Cancel-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=4008(CUPS-Accept-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fd128, op=4009(CUPS-Reject-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=(nil), op=8(Cancel-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fe370, name="@OWNER")
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fe370, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=0xb85fe370, op=400e(CUPS-Authenticate-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fac60, po=(nil), op=0(0x0000))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=4(Validate-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=38(Cancel-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=39(Cancel-My-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=3b(Close-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=4027(CUPS-Get-Document))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=(nil), op=5(Create-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fed18, op=2(Print-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fed18, op=3(Print-URI))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fed18, op=4(Validate-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=(nil), op=6(Send-Document))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fee40, name="@OWNER")
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb85fee40, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=7(Send-URI))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=c(Hold-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=d(Release-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=e(Restart-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=12(Purge-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=14(Set-Job-Attributes))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=17(Create-Job-Subscriptions))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=1a(Renew-Subscription))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=1b(Cancel-Subscription))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=1c(Get-Notifications))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=2c(Reprocess-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=2d(Cancel-Current-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=2e(Suspend-Current-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=2f(Resume-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=39(Cancel-My-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=3b(Close-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=400d(CUPS-Move-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb85fee40, op=4027(CUPS-Get-Document))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=(nil), op=4003(CUPS-Add-Modify-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb8600250, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb8600250, op=4004(CUPS-Delete-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb8600250, op=4006(CUPS-Add-Modify-Class))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb8600250, op=4007(CUPS-Delete-Class))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb8600250, op=400a(CUPS-Set-Default))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=(nil), op=10(Pause-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb86007b0, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=11(Resume-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=22(Enable-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=23(Disable-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=24(Pause-Printer-After-Current-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=25(Hold-New-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=26(Release-Held-New-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=27(Deactivate-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=28(Activate-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=29(Restart-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=2a(Shutdown-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=2b(Startup-Printer))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=30(Promote-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=31(Schedule-Job-After))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=38(Cancel-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=4008(CUPS-Accept-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86007b0, op=4009(CUPS-Reject-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=(nil), op=8(Cancel-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb86019f8, name="@OWNER")
d [14/Aug/2014:13:17:55 +0000] cupsdAddName(loc=0xb86019f8, name="@SYSTEM")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=0xb86019f8, op=400e(CUPS-Authenticate-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdAddPolicyOp(p=0xb85fe5f0, po=(nil), op=0(0x0000))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fe5f0, op=4(Validate-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fe5f0, op=38(Cancel-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fe5f0, op=39(Cancel-My-Jobs))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fe5f0, op=3b(Close-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fe5f0, op=4027(CUPS-Get-Document))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
I [14/Aug/2014:13:17:55 +0000] Remote access is disabled.
D [14/Aug/2014:13:17:55 +0000] Added auto ServerAlias clanton
I [14/Aug/2014:13:17:55 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
D [14/Aug/2014:13:17:55 +0000] Creating missing directory "/var/run/cups"
D [14/Aug/2014:13:17:55 +0000] Repairing ownership of "/var/run/cups"
D [14/Aug/2014:13:17:55 +0000] Repairing access permissions of "/var/run/cups"
D [14/Aug/2014:13:17:55 +0000] Creating missing directory "/var/run/cups/certs"
D [14/Aug/2014:13:17:55 +0000] Repairing ownership of "/var/run/cups/certs"
D [14/Aug/2014:13:17:55 +0000] Repairing access permissions of "/var/run/cups/certs"
I [14/Aug/2014:13:17:55 +0000] Using default TempDir of /var/spool/cups/tmp...
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_CACHEDIR=/var/cache/cups
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_DATADIR=/usr/share/cups
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_DOCROOT=/usr/share/cups/doc
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_FONTPATH=/usr/share/cups/fonts
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_REQUESTROOT=/var/spool/cups
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_SERVERBIN=/usr/lib/cups
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_SERVERROOT=/etc/cups
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_STATEDIR=/var/run/cups
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: HOME=/var/spool/cups/tmp
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: SERVER_ADMIN=root@clanton
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: SOFTWARE=CUPS/1.7.5
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: TMPDIR=/var/spool/cups/tmp
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: TZ=UTC
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: USER=root
d [14/Aug/2014:13:17:55 +0000] cupsdSetEnv: CUPS_MAX_MESSAGE=2047
I [14/Aug/2014:13:17:55 +0000] Configured for up to 100 clients.
I [14/Aug/2014:13:17:55 +0000] Allowing up to 100 client connections per host.
I [14/Aug/2014:13:17:55 +0000] Using policy "default" as the default.
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: NumPolicies=2
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: Policies[0]="authenticated"
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: Policies[1]="default"
I [14/Aug/2014:13:17:55 +0000] Full reload is required.
I [14/Aug/2014:13:17:55 +0000] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 3 filters...
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/octet-stream
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/pdf
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/postscript
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/rss+xml
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/vnd.cups-banner
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/vnd.cups-command
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/vnd.cups-pdf
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/vnd.cups-postscript
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/vnd.cups-ppd
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/vnd.cups-raster
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/vnd.cups-raw
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/x-cshell
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/x-csource
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/x-perl
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type application/x-shell
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/gif
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/jpeg
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/png
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/pwg-raster
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/tiff
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-alias
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-bitmap
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-icon
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-photocd
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-portable-anymap
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-portable-bitmap
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-portable-graymap
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-portable-pixmap
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-sgi-rgb
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-sun-raster
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-xbitmap
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type image/x-xpixmap
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type text/css
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type text/html
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: type text/plain
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: filter application/octet-stream to application/vnd.cups-raw 0 -
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: filter application/postscript to application/vnd.cups-postscript 66 pstops
d [14/Aug/2014:13:17:55 +0000] cupsdReadConfiguration: filter application/vnd.cups-raster to image/pwg-raster 100 rastertopwg
D [14/Aug/2014:13:17:55 +0000] Loading printer Kizuna...
d [14/Aug/2014:13:17:55 +0000] cupsdAddPrinter("Kizuna")
d [14/Aug/2014:13:17:55 +0000] cupsdAddPrinter: Adding Kizuna to Printers
d [14/Aug/2014:13:17:55 +0000] cupsdSetPrinterReasons(p=0xb860e980(Kizuna),s="-cups-insecure-filter-warning,cups-missing-filter-warning"
d [14/Aug/2014:13:17:55 +0000] cupsdFindBest: uri = "/printers/Kizuna"...
d [14/Aug/2014:13:17:55 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:17:55 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:17:55 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:17:55 +0000] cupsdFindBest: best = /
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=2(Print-Job))
d [14/Aug/2014:13:17:55 +0000] cupsdFindPolicyOp: Found exact match...
D [14/Aug/2014:13:17:55 +0000] load_ppd: Loading /var/cache/cups/Kizuna.data...
D [14/Aug/2014:13:17:55 +0000] cupsdMarkDirty(P----)
D [14/Aug/2014:13:17:55 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
D [14/Aug/2014:13:17:55 +0000] load_ppd: Loading /etc/cups/ppd/Kizuna.ppd...
D [14/Aug/2014:13:17:57 +0000] load_ppd: Saving /var/cache/cups/Kizuna.data...
d [14/Aug/2014:13:17:57 +0000] cupsdSetPrinterReasons(p=0xb860e980(Kizuna),s="-cups-missing-filter-warning,cups-insecure-filter-warning"
d [14/Aug/2014:13:17:57 +0000] add_printer_filter(p=0xb860e980(Kizuna), filtertype=0xb860e3f8(printer/Kizuna), filter="application/vnd.cups-raw application/octet-stream 0 -")
d [14/Aug/2014:13:17:57 +0000] add_printer_filter: Kizuna: adding filter application/vnd.cups-raw printer/Kizuna/application/octet-stream 0 -
d [14/Aug/2014:13:17:57 +0000] add_printer_filter: Kizuna: adding filter printer/Kizuna/application/octet-stream printer/Kizuna 0 -
d [14/Aug/2014:13:17:57 +0000] add_printer_filter(p=0xb860e980(Kizuna), filtertype=0xb860e3f8(printer/Kizuna), filter="application/vnd.cups-raster 0 /usr/lib/cups/filter/rastertokpsl_A3")
d [14/Aug/2014:13:17:57 +0000] Kizuna: File "/usr/lib/cups/filter/rastertokpsl_A3" permissions OK (040755/uid=0/gid=0).
d [14/Aug/2014:13:17:57 +0000] add_printer_filter: Kizuna: adding filter application/vnd.cups-raster printer/Kizuna 0 /usr/lib/cups/filter/rastertokpsl_A3
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: 37 types, 6 filters
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/octet-stream needs 2 filters
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/pdf not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/postscript not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/rss+xml not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/vnd.cups-banner not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/vnd.cups-command not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/vnd.cups-pdf not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/vnd.cups-postscript not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/vnd.cups-ppd not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/vnd.cups-raster needs 1 filters
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/vnd.cups-raw needs 1 filters
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/x-cshell not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/x-csource not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/x-perl not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: application/x-shell not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/gif not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/jpeg not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/png not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/pwg-raster not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/tiff not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-alias not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-bitmap not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-icon not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-photocd not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-portable-anymap not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-portable-bitmap not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-portable-graymap not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-portable-pixmap not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-sgi-rgb not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-sun-raster not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-xbitmap not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: image/x-xpixmap not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: text/css not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: text/html not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: text/plain not supported
d [14/Aug/2014:13:17:57 +0000] add_printer_formats: Kizuna: 3 supported types
D [14/Aug/2014:13:17:57 +0000] cupsdRegisterPrinter(p=0xb860e980(Kizuna))
I [14/Aug/2014:13:17:57 +0000] Loading job cache file "/var/cache/cups/job.cache"...
D [14/Aug/2014:13:17:57 +0000] [Job 1] Loading from cache...
D [14/Aug/2014:13:17:57 +0000] [Job 2] Loading from cache...
D [14/Aug/2014:13:17:57 +0000] [Job 3] Loading from cache...
I [14/Aug/2014:13:17:57 +0000] Full reload complete.
D [14/Aug/2014:13:17:57 +0000] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [14/Aug/2014:13:17:57 +0000] Cleaning out old files in "/var/spool/cups/tmp".
D [14/Aug/2014:13:17:57 +0000] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [14/Aug/2014:13:17:57 +0000] Cleaning out old files in "/var/cache/cups".
D [14/Aug/2014:13:17:57 +0000] Calling FindDeviceById(cups-Kizuna)
D [14/Aug/2014:13:17:57 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [14/Aug/2014:13:17:58 +0000] Using profile ID "Kizuna-Gray..".
D [14/Aug/2014:13:17:58 +0000] Calling CreateProfile(Kizuna-Gray..,temp)
W [14/Aug/2014:13:17:58 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [14/Aug/2014:13:17:58 +0000] Registering ICC color profiles for "Kizuna".
D [14/Aug/2014:13:17:58 +0000] Calling CreateDevice(cups-Kizuna,temp)
W [14/Aug/2014:13:17:58 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
d [14/Aug/2014:13:17:58 +0000] cupsdCreateProfile(job_id=0) = NULL
d [14/Aug/2014:13:17:58 +0000] cupsdStartListening: 3 Listeners
I [14/Aug/2014:13:17:58 +0000] Listening to [v1.::1]:631 on fd 9...
I [14/Aug/2014:13:17:58 +0000] Listening to 127.0.0.1:631 on fd 10...
I [14/Aug/2014:13:17:58 +0000] Listening to /var/run/cups/cups.sock:631 on fd 11...
d [14/Aug/2014:13:17:58 +0000] cupsdSetEnv: CUPS_SERVER=/var/run/cups/cups.sock
d [14/Aug/2014:13:17:58 +0000] cupsdSetEnv: CUPS_ENCRYPTION=IfRequested
d [14/Aug/2014:13:17:58 +0000] cupsdSetEnv: IPP_PORT=631
I [14/Aug/2014:13:17:58 +0000] Resuming new connection processing...
d [14/Aug/2014:13:17:58 +0000] cupsdResumeListening: Setting input bits...
d [14/Aug/2014:13:17:58 +0000] cupsdAddSelect(fd=9, read_cb=0xb7775bfa, write_cb=(nil), data=0xb85fa720)
d [14/Aug/2014:13:17:58 +0000] cupsdAddSelect(fd=10, read_cb=0xb7775bfa, write_cb=(nil), data=0xb85fa878)
d [14/Aug/2014:13:17:58 +0000] cupsdAddSelect(fd=11, read_cb=0xb7775bfa, write_cb=(nil), data=0xb85fa988)
d [14/Aug/2014:13:17:58 +0000] cupsdAddSelect(fd=12, read_cb=0xb7779bc0, write_cb=(nil), data=(nil))
D [14/Aug/2014:13:17:58 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
d [14/Aug/2014:13:17:58 +0000] cupsdAddCert: Adding certificate for PID 0
d [14/Aug/2014:13:17:58 +0000] cupsdAddCert: NumSystemGroups=1
d [14/Aug/2014:13:17:58 +0000] cupsdAddEvent(event=server-started, dest=(nil)(), job=(nil)(0), text="Scheduler started in foreground.", ...)
D [14/Aug/2014:13:17:58 +0000] Discarding unused server-started event...
d [14/Aug/2014:13:17:58 +0000] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0, curtime=1408022278
d [14/Aug/2014:13:17:58 +0000] select_timeout: JobHistoryUpdate=0
D [14/Aug/2014:13:17:59 +0000] Report: clients=0
D [14/Aug/2014:13:17:59 +0000] Report: jobs=3
D [14/Aug/2014:13:17:59 +0000] Report: jobs-active=0
D [14/Aug/2014:13:17:59 +0000] Report: printers=1
D [14/Aug/2014:13:17:59 +0000] Report: stringpool-string-count=10275
D [14/Aug/2014:13:17:59 +0000] Report: stringpool-alloc-bytes=9712
D [14/Aug/2014:13:17:59 +0000] Report: stringpool-total-bytes=185152
d [14/Aug/2014:13:17:59 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:17:59 +0000] select_timeout(0): 27 seconds to write dirty config/state files
d [14/Aug/2014:13:18:23 +0000] cupsdAcceptClient(lis=0xb85fa988(11)) Clients=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] Accepted from localhost (Domain)
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=(nil), data=0xb861f1e8)
D [14/Aug/2014:13:18:23 +0000] [Client 14] Waiting for request.
d [14/Aug/2014:13:18:23 +0000] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0, curtime=1408022303
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] POST / HTTP/1.1
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: uri = "/"...
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: best = /
d [14/Aug/2014:13:18:23 +0000] [Client 14] con->uri="/", con->best=0xb85fa450(/)
d [14/Aug/2014:13:18:23 +0000] [Client 14] Authorization=""
D [14/Aug/2014:13:18:23 +0000] [Client 14] No authentication data provided.
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/", con->best=0xb85fa450(/)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=193, request=0xb860b970(IPP_IDLE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=185, request=0xb860b970(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=156, request=0xb860b970(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=119, request=0xb860b970(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=67, request=0xb860b970(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=30, request=0xb860b970(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1, request=0xb860b970(IPP_ATTRIBUTE), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] 2.0 Get-Printer-Attributes 1
d [14/Aug/2014:13:18:23 +0000] cupsdProcessIPPRequest(0xb861f1e8[14]): operation_id = 000b
D [14/Aug/2014:13:18:23 +0000] Get-Printer-Attributes ipp://localhost:631/printers/kizuna1
d [14/Aug/2014:13:18:23 +0000] get_printer_attrs(0xb861f1e8[14], ipp://localhost:631/printers/kizuna1)
D [14/Aug/2014:13:18:23 +0000] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [14/Aug/2014:13:18:23 +0000] [Client 14] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost:631/printers/kizuna1) from localhost
D [14/Aug/2014:13:18:23 +0000] [Client 14] Content-Length: 130
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=0xb77769d5, data=0xb861f1e8)
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0xb86234b8(IPP_IDLE), pipe_pid=0, file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] bytes=0, http_state=0, data_remaining=0
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=(nil), data=0xb861f1e8)
D [14/Aug/2014:13:18:23 +0000] [Client 14] Waiting for request.
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] POST /admin/ HTTP/1.1
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: uri = "/admin/"...
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: best = /admin
d [14/Aug/2014:13:18:23 +0000] [Client 14] con->uri="/admin/", con->best=0xb85fa5a0(/admin)
d [14/Aug/2014:13:18:23 +0000] [Client 14] Authorization=""
D [14/Aug/2014:13:18:23 +0000] [Client 14] No authentication data provided.
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0xb85fa5a0(/admin)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Aug/2014:13:18:23 +0000] [Client 14] 2.0 CUPS-Add-Modify-Printer 2
d [14/Aug/2014:13:18:23 +0000] cupsdProcessIPPRequest(0xb861f1e8[14]): operation_id = 4003
D [14/Aug/2014:13:18:23 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/kizuna1
d [14/Aug/2014:13:18:23 +0000] add_printer(0xb861f1e8[14], ipp://localhost:631/printers/kizuna1)
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=4003(CUPS-Add-Modify-Printer))
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0xb85fcac0((null))
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: op=4003(CUPS-Add-Modify-Printer)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: username=""
D [14/Aug/2014:13:18:23 +0000] [Client 14] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/kizuna1) from localhost
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdSendError code=401, auth_type=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] WWW-Authenticate: Basic realm="CUPS", trc="y"
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0xb86234b8(IPP_DATA), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [14/Aug/2014:13:18:23 +0000] [Client 14] Closing connection.
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdRemoveSelect(fd=14)
d [14/Aug/2014:13:18:23 +0000] cupsdAcceptClient(lis=0xb85fa988(11)) Clients=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] Accepted from localhost (Domain)
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=(nil), data=0xb861f1e8)
D [14/Aug/2014:13:18:23 +0000] [Client 14] Waiting for request.
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] cupsdAcceptClient(lis=0xb85fa988(11)) Clients=1
D [14/Aug/2014:13:18:23 +0000] [Client 15] Accepted from localhost (Domain)
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=15, read_cb=0xb77770d0, write_cb=(nil), data=0xb869c378)
D [14/Aug/2014:13:18:23 +0000] [Client 15] Waiting for request.
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [14/Aug/2014:13:18:23 +0000] [Client 14] Closing connection.
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdRemoveSelect(fd=14)
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] cupsdAcceptClient(lis=0xb85fa988(11)) Clients=1
D [14/Aug/2014:13:18:23 +0000] [Client 14] Accepted from localhost (Domain)
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=(nil), data=0xb861f1e8)
D [14/Aug/2014:13:18:23 +0000] [Client 14] Waiting for request.
d [14/Aug/2014:13:18:23 +0000] [Client 15] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [14/Aug/2014:13:18:23 +0000] [Client 15] Closing connection.
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdRemoveSelect(fd=15)
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] POST /admin/ HTTP/1.1
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: uri = "/admin/"...
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: best = /admin
d [14/Aug/2014:13:18:23 +0000] [Client 14] con->uri="/admin/", con->best=0xb85fa5a0(/admin)
d [14/Aug/2014:13:18:23 +0000] [Client 14] Authorization="PeerCred root"
D [14/Aug/2014:13:18:23 +0000] [Client 14] Authorized as root using PeerCred
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0xb85fa5a0(/admin)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Aug/2014:13:18:23 +0000] [Client 14] 2.0 CUPS-Add-Modify-Printer 2
d [14/Aug/2014:13:18:23 +0000] cupsdProcessIPPRequest(0xb861f1e8[14]): operation_id = 4003
D [14/Aug/2014:13:18:23 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/kizuna1
d [14/Aug/2014:13:18:23 +0000] add_printer(0xb861f1e8[14], ipp://localhost:631/printers/kizuna1)
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=4003(CUPS-Add-Modify-Printer))
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0xb85fcac0((null))
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: op=4003(CUPS-Add-Modify-Printer)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: username="root"
d [14/Aug/2014:13:18:23 +0000] cupsdAddPrinter("kizuna1")
d [14/Aug/2014:13:18:23 +0000] cupsdAddPrinter: Adding kizuna1 to Printers
I [14/Aug/2014:13:18:23 +0000] Setting kizuna1 printer-is-accepting-jobs to 1 (was 0.)
d [14/Aug/2014:13:18:23 +0000] cupsdAddEvent(event=printer-state-changed, dest=0xb86270e8(kizuna1), job=(nil)(0), text="%s accepting jobs.", ...)
D [14/Aug/2014:13:18:23 +0000] Discarding unused printer-state-changed event...
I [14/Aug/2014:13:18:23 +0000] Setting kizuna1 printer-state to 3 (was 5.)
d [14/Aug/2014:13:18:23 +0000] cupsdAddEvent(event=printer-state-changed, dest=0xb86270e8(kizuna1), job=(nil)(0), text="%s "%s" state changed to %s.", ...)
D [14/Aug/2014:13:18:23 +0000] Discarding unused printer-state-changed event...
d [14/Aug/2014:13:18:23 +0000] cupsdSetPrinterReasons(p=0xb86270e8(kizuna1),s="-paused"
d [14/Aug/2014:13:18:23 +0000] set_printer_defaults: printer-state
d [14/Aug/2014:13:18:23 +0000] set_printer_defaults: printer-is-accepting-jobs
d [14/Aug/2014:13:18:23 +0000] cupsdSetPrinterReasons(p=0xb86270e8(kizuna1),s="-cups-insecure-filter-warning,cups-missing-filter-warning"
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: uri = "/printers/kizuna1"...
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: best = /
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=2(Print-Job))
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp: Found exact match...
D [14/Aug/2014:13:18:23 +0000] cupsdMarkDirty(P----)
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [14/Aug/2014:13:18:23 +0000] load_ppd: Loading /etc/cups/ppd/kizuna1.ppd...
d [14/Aug/2014:13:18:23 +0000] cupsdSetPrinterReasons(p=0xb86270e8(kizuna1),s="-cups-missing-filter-warning,cups-insecure-filter-warning"
d [14/Aug/2014:13:18:23 +0000] add_printer_filter(p=0xb86270e8(kizuna1), filtertype=0xb86235b0(printer/kizuna1), filter="application/vnd.cups-raw 0 -")
d [14/Aug/2014:13:18:23 +0000] add_printer_filter: kizuna1: adding filter application/vnd.cups-raw printer/kizuna1 0 -
d [14/Aug/2014:13:18:23 +0000] add_printer_filter(p=0xb86270e8(kizuna1), filtertype=0xb86235b0(printer/kizuna1), filter="application/vnd.cups-postscript 0 -")
d [14/Aug/2014:13:18:23 +0000] add_printer_filter: kizuna1: adding filter application/vnd.cups-postscript printer/kizuna1 0 -
D [14/Aug/2014:13:18:23 +0000] cupsdRegisterPrinter(p=0xb86270e8(kizuna1))
D [14/Aug/2014:13:18:23 +0000] cupsdMarkDirty(P----)
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [14/Aug/2014:13:18:23 +0000] cupsdMarkDirty(--p--)
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdAddEvent(event=printer-added, dest=0xb86270e8(kizuna1), job=(nil)(0), text="New printer "%s" added by "%s".", ...)
D [14/Aug/2014:13:18:23 +0000] Discarding unused printer-added event...
I [14/Aug/2014:13:18:23 +0000] New printer "kizuna1" added by "root".
D [14/Aug/2014:13:18:23 +0000] [Client 14] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/kizuna1) from localhost
D [14/Aug/2014:13:18:23 +0000] [Client 14] Content-Length: 75
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=0xb77769d5, data=0xb861f1e8)
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xb8623518(IPP_IDLE), pipe_pid=0, file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] bytes=0, http_state=0, data_remaining=0
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=(nil), data=0xb861f1e8)
D [14/Aug/2014:13:18:23 +0000] [Client 14] Waiting for request.
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] POST / HTTP/1.1
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: uri = "/"...
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: best = /
d [14/Aug/2014:13:18:23 +0000] [Client 14] con->uri="/", con->best=0xb85fa450(/)
d [14/Aug/2014:13:18:23 +0000] [Client 14] Authorization="PeerCred root"
D [14/Aug/2014:13:18:23 +0000] [Client 14] Authorized as root using PeerCred
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/", con->best=0xb85fa450(/)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Aug/2014:13:18:23 +0000] [Client 14] 2.0 Get-Printer-Attributes 3
d [14/Aug/2014:13:18:23 +0000] cupsdProcessIPPRequest(0xb861f1e8[14]): operation_id = 000b
D [14/Aug/2014:13:18:23 +0000] Get-Printer-Attributes ipp://localhost:631/printers/kizuna1
d [14/Aug/2014:13:18:23 +0000] get_printer_attrs(0xb861f1e8[14], ipp://localhost:631/printers/kizuna1)
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=b(Get-Printer-Attributes))
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp: Found wildcard match...
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/", con->best=0xb85fe5b8((null))
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: op=0(0x0000)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Aug/2014:13:18:23 +0000] copy_attrs(to=0xb8623e20, from=0xb8623ad0, ra=0xb8605f10, group=0, quickcopy=0)
d [14/Aug/2014:13:18:23 +0000] copy_attrs(to=0xb8623e20, from=0xb8623d08, ra=0xb8605f10, group=0, quickcopy=0)
d [14/Aug/2014:13:18:23 +0000] copy_attrs(to=0xb8623e20, from=0xb860e588, ra=0xb8605f10, group=0, quickcopy=-2147483648)
D [14/Aug/2014:13:18:23 +0000] [Client 14] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/kizuna1) from localhost
D [14/Aug/2014:13:18:23 +0000] [Client 14] Content-Length: 97
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=0xb77769d5, data=0xb861f1e8)
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=97, response=0xb8623e20(IPP_IDLE), pipe_pid=0, file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [14/Aug/2014:13:18:23 +0000] [Client 14] bytes=0, http_state=0, data_remaining=0
d [14/Aug/2014:13:18:23 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=(nil), data=0xb861f1e8)
D [14/Aug/2014:13:18:23 +0000] [Client 14] Waiting for request.
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] POST /admin/ HTTP/1.1
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: uri = "/admin/"...
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: best = /admin
d [14/Aug/2014:13:18:23 +0000] [Client 14] con->uri="/admin/", con->best=0xb85fa5a0(/admin)
d [14/Aug/2014:13:18:23 +0000] [Client 14] Authorization="PeerCred root"
D [14/Aug/2014:13:18:23 +0000] [Client 14] Authorized as root using PeerCred
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0xb85fa5a0(/admin)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=46080, request=0xb8623e20(IPP_IDLE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=46072, request=0xb8623e20(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=46043, request=0xb8623e20(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=46006, request=0xb8623e20(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=45954, request=0xb8623e20(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=45925, request=0xb8623e20(IPP_ATTRIBUTE), file=-1
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=45881, request=0xb8623e20(IPP_ATTRIBUTE), file=-1
D [14/Aug/2014:13:18:23 +0000] [Client 14] 2.0 CUPS-Add-Modify-Printer 4
d [14/Aug/2014:13:18:23 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:23 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=13112, request=0xb8623e20(IPP_DATA), file=15
d [14/Aug/2014:13:18:23 +0000] cupsdProcessIPPRequest(0xb861f1e8[14]): operation_id = 4003
D [14/Aug/2014:13:18:23 +0000] CUPS-Add-Modify-Printer ipp://localhost:631/printers/kizuna1
d [14/Aug/2014:13:18:23 +0000] add_printer(0xb861f1e8[14], ipp://localhost:631/printers/kizuna1)
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=4003(CUPS-Add-Modify-Printer))
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0xb85fcac0((null))
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: op=4003(CUPS-Add-Modify-Printer)
d [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Aug/2014:13:18:23 +0000] cupsdIsAuthorized: username="root"
D [14/Aug/2014:13:18:23 +0000] kizuna1 device-uri: OK
I [14/Aug/2014:13:18:23 +0000] Setting kizuna1 device-uri to "socket://10.191.21.237:9100/" (was "file:///dev/null".)
d [14/Aug/2014:13:18:23 +0000] set_printer_defaults: device-uri
d [14/Aug/2014:13:18:23 +0000] copy_file("/var/spool/cups/00000000", "/etc/cups/ppd/kizuna1.ppd")
D [14/Aug/2014:13:18:23 +0000] Copied PPD file successfully
d [14/Aug/2014:13:18:23 +0000] cupsdSetPrinterReasons(p=0xb86270e8(kizuna1),s="none"
D [14/Aug/2014:13:18:23 +0000] cupsdMarkDirty(P----)
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [14/Aug/2014:13:18:23 +0000] Calling FindDeviceById(cups-kizuna1)
D [14/Aug/2014:13:18:23 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [14/Aug/2014:13:18:23 +0000] Using profile ID "kizuna1-Gray..".
D [14/Aug/2014:13:18:23 +0000] Calling CreateProfile(kizuna1-Gray..,temp)
W [14/Aug/2014:13:18:23 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [14/Aug/2014:13:18:23 +0000] Registering ICC color profiles for "kizuna1".
D [14/Aug/2014:13:18:23 +0000] Calling CreateDevice(cups-kizuna1,temp)
W [14/Aug/2014:13:18:23 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
d [14/Aug/2014:13:18:23 +0000] cupsdSetPrinterReasons(p=0xb86270e8(kizuna1),s="-cups-insecure-filter-warning,cups-missing-filter-warning"
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: uri = "/printers/kizuna1"...
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: Location / Limit 7f
d [14/Aug/2014:13:18:23 +0000] cupsdFindBest: best = /
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp(p=0xb85fac60, op=2(Print-Job))
d [14/Aug/2014:13:18:23 +0000] cupsdFindPolicyOp: Found exact match...
D [14/Aug/2014:13:18:23 +0000] cupsdMarkDirty(P----)
D [14/Aug/2014:13:18:23 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [14/Aug/2014:13:18:23 +0000] load_ppd: Loading /etc/cups/ppd/kizuna1.ppd...
D [14/Aug/2014:13:18:26 +0000] load_ppd: Saving /var/cache/cups/kizuna1.data...
d [14/Aug/2014:13:18:26 +0000] cupsdSetPrinterReasons(p=0xb86270e8(kizuna1),s="-cups-missing-filter-warning,cups-insecure-filter-warning"
d [14/Aug/2014:13:18:26 +0000] add_printer_filter(p=0xb86270e8(kizuna1), filtertype=0xb86235b0(printer/kizuna1), filter="application/vnd.cups-raw application/octet-stream 0 -")
d [14/Aug/2014:13:18:26 +0000] add_printer_filter: kizuna1: adding filter application/vnd.cups-raw printer/kizuna1/application/octet-stream 0 -
d [14/Aug/2014:13:18:26 +0000] add_printer_filter: kizuna1: adding filter printer/kizuna1/application/octet-stream printer/kizuna1 0 -
d [14/Aug/2014:13:18:26 +0000] add_printer_filter(p=0xb86270e8(kizuna1), filtertype=0xb86235b0(printer/kizuna1), filter="application/vnd.cups-raster 0 /usr/lib/cups/filter/rastertokpsl_A3")
d [14/Aug/2014:13:18:26 +0000] kizuna1: File "/usr/lib/cups/filter/rastertokpsl_A3" permissions OK (040755/uid=0/gid=0).
d [14/Aug/2014:13:18:26 +0000] add_printer_filter: kizuna1: adding filter application/vnd.cups-raster printer/kizuna1 0 /usr/lib/cups/filter/rastertokpsl_A3
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: 39 types, 9 filters
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/octet-stream needs 2 filters
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/pdf not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/postscript not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/rss+xml not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/vnd.cups-banner not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/vnd.cups-command not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/vnd.cups-pdf not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/vnd.cups-postscript not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/vnd.cups-ppd not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/vnd.cups-raster needs 1 filters
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/vnd.cups-raw needs 1 filters
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/x-cshell not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/x-csource not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/x-perl not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: application/x-shell not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/gif not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/jpeg not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/png not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/pwg-raster not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/tiff not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-alias not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-bitmap not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-icon not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-photocd not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-portable-anymap not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-portable-bitmap not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-portable-graymap not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-portable-pixmap not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-sgi-rgb not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-sun-raster not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-xbitmap not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: image/x-xpixmap not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: text/css not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: text/html not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: text/plain not supported
d [14/Aug/2014:13:18:26 +0000] add_printer_formats: kizuna1: 3 supported types
D [14/Aug/2014:13:18:26 +0000] cupsdRegisterPrinter(p=0xb86270e8(kizuna1))
D [14/Aug/2014:13:18:26 +0000] cupsdMarkDirty(P----)
D [14/Aug/2014:13:18:26 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [14/Aug/2014:13:18:26 +0000] cupsdMarkDirty(--p--)
D [14/Aug/2014:13:18:26 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
d [14/Aug/2014:13:18:26 +0000] cupsdAddEvent(event=printer-modified, dest=0xb86270e8(kizuna1), job=(nil)(0), text="Printer "%s" modified by "%s".", ...)
D [14/Aug/2014:13:18:26 +0000] Discarding unused printer-modified event...
I [14/Aug/2014:13:18:26 +0000] Printer "kizuna1" modified by "root".
D [14/Aug/2014:13:18:26 +0000] [Client 14] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/kizuna1) from localhost
D [14/Aug/2014:13:18:26 +0000] [Client 14] Content-Length: 75
d [14/Aug/2014:13:18:26 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=0xb77769d5, data=0xb861f1e8)
I [14/Aug/2014:13:18:26 +0000] Saving printers.conf...
I [14/Aug/2014:13:18:26 +0000] Generating printcap /etc/printcap...
D [14/Aug/2014:13:18:26 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients and dirty files"
d [14/Aug/2014:13:18:26 +0000] select_timeout: JobHistoryUpdate=0
D [14/Aug/2014:13:18:26 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xb8623428(IPP_IDLE), pipe_pid=0, file=-1
D [14/Aug/2014:13:18:26 +0000] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [14/Aug/2014:13:18:26 +0000] [Client 14] bytes=0, http_state=0, data_remaining=0
d [14/Aug/2014:13:18:26 +0000] cupsdAddSelect(fd=14, read_cb=0xb77770d0, write_cb=(nil), data=0xb861f1e8)
D [14/Aug/2014:13:18:26 +0000] [Client 14] Waiting for request.
D [14/Aug/2014:13:18:26 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [14/Aug/2014:13:18:26 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:26 +0000] [Client 14] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [14/Aug/2014:13:18:26 +0000] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [14/Aug/2014:13:18:26 +0000] [Client 14] Closing connection.
D [14/Aug/2014:13:18:26 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
d [14/Aug/2014:13:18:26 +0000] cupsdRemoveSelect(fd=14)
d [14/Aug/2014:13:18:26 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:27 +0000] select_timeout: JobHistoryUpdate=0
d [14/Aug/2014:13:18:27 +0000] select_timeout(0): 86400 seconds to do nothing