Attachment 157121 Details for Bug 244001 – CUPS error log

CUPS error log

error_log (text/plain), 157.08 KB, created by Nicolò on 2007-06-15 15:10:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Nicolò

Created: 2007-06-15 15:10:07 UTC

Size: 157.08 KB

patch

obsolete

>I [15/Jun/2007:16:56:06 +0200] Listening to 127.0.0.1:631 (IPv4)
>I [15/Jun/2007:16:56:06 +0200] Listening to /var/run/cups/cups.sock (Domain)
>d [15/Jun/2007:16:56:06 +0200] cupsdAddLocation: added location 'CUPS_INTERNAL_BROWSE_ACL'
>d [15/Jun/2007:16:56:06 +0200] cupsdAllowHost(loc=0x80995158(CUPS_INTERNAL_BROWSE_ACL), name="@LOCAL")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddLocation: added location '/'
>d [15/Jun/2007:16:56:06 +0200] cupsdAllowHost(loc=0x809995a8(/), name="localhost")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddLocation: added location '/admin'
>d [15/Jun/2007:16:56:06 +0200] cupsdAllowHost(loc=0x80999630(/admin), name="localhost")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddLocation: added location '/admin/conf'
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x809996b8, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAllowHost(loc=0x809996b8(/admin/conf), name="localhost")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=(nil), op=6(Send-Document))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x809997a0, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x809997a0, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=7(Send-URI))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999810, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999810, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=c(Hold-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999880, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999880, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=d(Release-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999908, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999908, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=e(Restart-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999968, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999968, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=12(Purge-Jobs))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x809999f8, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x809999f8, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=14(Set-Job-Attributes))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999a68, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999a68, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=17(Create-Job-Subscription))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999b00, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999b00, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=1a(Renew-Subscription))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999b70, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999b70, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=1b(Cancel-Subscription))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999c10, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999c10, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=1c(Get-Notifications))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999c80, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999c80, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=2c(Reprocess-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999d28, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999d28, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=2d(Cancel-Current-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999d98, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999d98, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=2e(Suspend-Current-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999e48, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999e48, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=2f(Resume-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999eb8, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999eb8, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x809997a0, op=400d(CUPS-Move-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999e08, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999e08, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=(nil), op=10(Pause-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x80999fa0, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=11(Resume-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a050, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=13(Set-Printer-Attributes))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a0b0, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=22(Enable-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a000, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=23(Disable-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a178, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=24(Pause-Printer-After-Current-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a110, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=25(Hold-New-Jobs))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a248, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=26(Release-Held-New-Jobs))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a1d8, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=27(Deactivate-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a310, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=28(Activate-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a2a8, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=29(Restart-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a3e0, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=2a(Shutdown-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a370, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=2b(Startup-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a4b8, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=30(Promote-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a440, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=31(Schedule-Job-After))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a588, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=4003(CUPS-Add-Modify-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a508, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=4004(CUPS-Delete-Printer))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a660, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=4006(CUPS-Add-Modify-Class))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a5d8, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=4007(CUPS-Delete-Class))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a730, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=4008(CUPS-Accept-Jobs))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a6a0, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=4009(CUPS-Reject-Jobs))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a808, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x80999fa0, op=400a(CUPS-Set-Default))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a770, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=(nil), op=8(Cancel-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a7c0, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a7c0, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=0x8099a7c0, op=400e(CUPS-Authenticate-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a848, name="@OWNER")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddName(loc=0x8099a848, name="@SYSTEM")
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPolicyOp(p=0x80999770, po=(nil), op=0(unknown-0000))
>I [15/Jun/2007:16:56:06 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
>I [15/Jun/2007:16:56:06 +0200] Using default TempDir of /var/spool/cups/tmp...
>I [15/Jun/2007:16:56:06 +0200] Cleaning out old temporary files in "/var/spool/cups/tmp"...
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_CACHEDIR=/var/cache/cups
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_DATADIR=/usr/share/cups
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_DOCROOT=/usr/share/doc/cups-1.2.10
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_FONTPATH=/usr/share/cups/fonts
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_REQUESTROOT=/var/spool/cups
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_SERVERBIN=/usr/lib/cups
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_SERVERROOT=/etc/cups
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_STATEDIR=/var/run/cups
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: SERVER_ADMIN=root@localhost.localdomain
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: SOFTWARE=CUPS/1.2.10
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: TMPDIR=/var/spool/cups/tmp
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: USER=root
>I [15/Jun/2007:16:56:06 +0200] Configured for up to 100 clients.
>I [15/Jun/2007:16:56:06 +0200] Allowing up to 100 client connections per host.
>I [15/Jun/2007:16:56:06 +0200] Using policy "default" as the default!
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: NumPolicies=1
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: Policies[0]="default"
>I [15/Jun/2007:16:56:06 +0200] Full reload is required.
>I [15/Jun/2007:16:56:06 +0200] Loaded MIME database from '/etc/cups': 34 types, 38 filters...
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/octet-stream
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/pdf
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/postscript
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.cups-command
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.cups-form
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.cups-pdf
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.cups-postscript
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.cups-ppd
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.cups-raster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.cups-raw
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/vnd.hp-hpgl
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/x-cshell
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/x-csource
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/x-perl
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type application/x-shell
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/gif
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/jpeg
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/png
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/tiff
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-alias
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-bitmap
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-icon
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-photocd
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-portable-anymap
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-portable-bitmap
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-portable-graymap
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-portable-pixmap
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-sgi-rgb
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-sun-raster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-xbitmap
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type image/x-xpixmap
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type text/css
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type text/html
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: type text/plain
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/pdf to application/postscript 33 pdftops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/postscript to application/vnd.cups-postscript 66 pstops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/vnd.cups-postscript to application/vnd.cups-raster 100 pstoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/vnd.hp-hpgl to application/postscript 66 hpgltops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/x-cshell to application/postscript 33 texttops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/x-csource to application/postscript 33 texttops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/x-perl to application/postscript 33 texttops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter application/x-shell to application/postscript 33 texttops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/gif to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/gif to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/png to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/png to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-postscript 66 imagetops
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-raster 100 imagetoraster
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter text/html to application/postscript 33 texttopaps
>d [15/Jun/2007:16:56:06 +0200] cupsdReadConfiguration: filter text/plain to application/postscript 33 texttopaps
>D [15/Jun/2007:16:56:06 +0200] Loading printer Hp...
>d [15/Jun/2007:16:56:06 +0200] cupsdAddPrinter("Hp")
>d [15/Jun/2007:16:56:06 +0200] cupsdFindBest: uri = "/printers/Hp"...
>d [15/Jun/2007:16:56:06 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:06 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:06 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:06 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:06 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:06 +0200] cupsdFindPolicyOp(p=0x80999770, op=2(Print-Job))
>d [15/Jun/2007:16:56:06 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:06 +0200] add_printer_filter: Hp: adding filter application/vnd.cups-raw printer/Hp 0 -
>d [15/Jun/2007:16:56:06 +0200] add_printer_filter: Hp: adding filter application/vnd.cups-postscript printer/Hp 0 foomatic-rip
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: 35 types, 40 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/octet-stream not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/pdf needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/postscript needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.cups-command not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.cups-form not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.cups-pdf not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.cups-postscript needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.cups-ppd not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.cups-raster not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.cups-raw needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/vnd.hp-hpgl needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/x-cshell needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/x-csource needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/x-perl needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: application/x-shell needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/gif needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/jpeg needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/png needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/tiff needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-alias not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-bitmap needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-icon not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-photocd needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-portable-anymap needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-portable-bitmap needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-portable-graymap needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-portable-pixmap needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-sgi-rgb needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-sun-raster needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-xbitmap needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: image/x-xpixmap needs 1 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: printer/Hp not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: text/css not supported
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: text/html needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: text/plain needs 2 filters
>d [15/Jun/2007:16:56:06 +0200] add_printer_formats: Hp: 26 supported types
>I [15/Jun/2007:16:56:06 +0200] Loading job cache file "/var/cache/cups/job.cache"...
>D [15/Jun/2007:16:56:06 +0200] Loading job 1 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 2 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 3 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 4 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 5 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 6 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 7 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 8 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 9 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 10 from cache...
>D [15/Jun/2007:16:56:06 +0200] Loading job 11 from cache...
>I [15/Jun/2007:16:56:06 +0200] Full reload complete.
>d [15/Jun/2007:16:56:06 +0200] cupsdStartListening: 2 Listeners
>I [15/Jun/2007:16:56:06 +0200] Listening to 127.0.0.1:631 on fd 3...
>I [15/Jun/2007:16:56:06 +0200] Listening to /var/run/cups/cups.sock on fd 4...
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_SERVER=/var/run/cups/cups.sock
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: CUPS_ENCRYPTION=IfRequested
>d [15/Jun/2007:16:56:06 +0200] cupsdSetEnv: IPP_PORT=631
>d [15/Jun/2007:16:56:06 +0200] cupsdResumeListening: Setting input bits...
>d [15/Jun/2007:16:56:06 +0200] cupsdResumeListening: Adding fd 3 to InputSet...
>d [15/Jun/2007:16:56:06 +0200] cupsdResumeListening: Adding fd 4 to InputSet...
>d [15/Jun/2007:16:56:06 +0200] cupsdStartBrowsing: Adding fd 5 to InputSet...
>d [15/Jun/2007:16:56:06 +0200] cupsdStartServer: Adding fd 6 to InputSet...
>d [15/Jun/2007:16:56:06 +0200] cupsdAddCert: adding certificate for pid 0
>d [15/Jun/2007:16:56:06 +0200] cupsdAddCert: NumSystemGroups=2
>d [15/Jun/2007:16:56:06 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:56:07 +0200] stringpool: 310 strings, 6768 allocated, 6144 total bytes
>d [15/Jun/2007:16:56:07 +0200] select_timeout: 31 seconds to send browse update
>d [15/Jun/2007:16:56:38 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:56:38 +0200] select_timeout: 31 seconds to send browse update
>d [15/Jun/2007:16:56:38 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:56:38 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:38 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:56:38 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:56:38 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:39 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:39 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:39 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:39 +0200] POST /
>d [15/Jun/2007:16:56:39 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=105, con->file=-1
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 8, used=97, file=-1 state=6
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=97, con->file=-1
>d [15/Jun/2007:16:56:39 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4001
>D [15/Jun/2007:16:56:39 +0200] CUPS-Get-Default
>d [15/Jun/2007:16:56:39 +0200] get_default(0x809c0d48[8])
>d [15/Jun/2007:16:56:39 +0200] cupsdFindPolicyOp(p=0x80999770, op=4001(CUPS-Get-Default))
>d [15/Jun/2007:16:56:39 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:39 +0200] add_printer_state_reasons(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:39 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:39 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:39 +0200] copy_attrs(to=0x809c4560, from=0x809a46c8, ra=(nil), group=0, quickcopy=0)
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a46f0[uri-authentication-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4780[uri-security-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a48e8[printer-more-info,4,45])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4938[job-quota-period,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4988[job-k-limit,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a49d0[job-page-limit,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4a20[job-sheets-default,4,42])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4a98[device-uri,4,45])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809b2cd0[color-supported,4,22])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809abb30[pages-per-minute,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809abba8[printer-make-and-model,4,41])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a5938[media-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a5e28[media-default,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a6098[port-monitor,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a60e8[port-monitor-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4ad0[finishings-supported,4,23])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4b28[finishings-default,4,23])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a8eb8[document-format-supported,4,49])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a9038[copies-default,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a9088[job-hold-until-default,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a90c0[job-priority-default,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a8640[number-up-default,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a8690[orientation-requested-default,4,23])
>d [15/Jun/2007:16:56:39 +0200] copy_attrs(to=0x809c4560, from=0x80994030, ra=(nil), group=0, quickcopy=-2147483648)
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4670[charset-configured,4,47])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a58d8[charset-supported,4,47])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x80994738[compression-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4608[copies-supported,4,33])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4538[document-format-default,4,49])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a44e0[generated-natural-language-supported,4,48])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809947b8[ipp-versions-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x80994340[job-hold-until-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a44b8[job-priority-supported,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x80994548[job-sheets-supported,4,42])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809942a0[multiple-document-handling-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a41d0[multiple-document-jobs-supported,4,22])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a3a48[multiple-operation-time-out,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a39e8[natural-language-configured,4,48])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a45b8[notify-attributes-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a3988[notify-lease-duration-default,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a3930[notify-lease-duration-supported,4,33])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a37e0[notify-max-events-supported,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x80994820[notify-events-default,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a3b20[notify-events-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x80994858[notify-pull-method-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a3880[notify-schemes-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4218[number-up-supported,4,21])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a42a0[operations-supported,4,23])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a8e70[orientation-requested-supported,4,23])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809942f8[page-ranges-supported,4,22])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x80994120[pdl-override-supported,4,44])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809941c0[printer-error-policy-supported,4,42])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x80994090[printer-op-policy-supported,4,42])
>D [15/Jun/2007:16:56:39 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:39 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:56:39 +0200] select_timeout: 30 seconds to send browse update
>d [15/Jun/2007:16:56:39 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:56:39 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:56:39 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 1
>D [15/Jun/2007:16:56:39 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:39 +0200] cupsdAcceptClient: 10 from localhost (Domain)
>d [15/Jun/2007:16:56:39 +0200] cupsdAcceptClient: 10 connected to server on localhost:631
>d [15/Jun/2007:16:56:39 +0200] cupsdAcceptClient: Adding fd 10 to InputSet...
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:39 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:56:39 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 10, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 10 POST / HTTP/1.1
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:39 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:39 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:39 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:39 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:39 +0200] POST /
>d [15/Jun/2007:16:56:39 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 10 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 10, used=279, file=-1 state=6
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 10 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:56:39 +0200] cupsdProcessIPPRequest(0x809c7840[10]): operation_id = 4002
>D [15/Jun/2007:16:56:39 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:56:39 +0200] get_printers(0x809c7840[10], 0)
>d [15/Jun/2007:16:56:39 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:56:39 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:39 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:39 +0200] add_queued_job_count(0x809c7840[10], 0x80993648[Hp])
>d [15/Jun/2007:16:56:39 +0200] copy_attrs(to=0x809c4560, from=0x809a46c8, ra=0x809c46d0, group=0, quickcopy=0)
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:39 +0200] copy_attribute(0x809c4560, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:39 +0200] copy_attrs(to=0x809c4560, from=0x80994030, ra=0x809c46d0, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:56:39 +0200] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:39 +0200] cupsdProcessIPPRequest: Adding fd 10 to OutputSet...
>d [15/Jun/2007:16:56:39 +0200] select_timeout: 30 seconds to send browse update
>d [15/Jun/2007:16:56:39 +0200] cupsdWriteClient: 10 bytes < 0
>d [15/Jun/2007:16:56:39 +0200] cupsdWriteClient: Removing fd 10 from OutputSet...
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: 10, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:39 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:39 +0200] cupsdCloseClient: 10
>d [15/Jun/2007:16:56:39 +0200] cupsdCloseClient: Removing fd 10 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:40 +0200] select_timeout: 29 seconds to send browse update
>d [15/Jun/2007:16:56:42 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:56:42 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:42 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:56:42 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:56:42 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:56:42 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:42 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:56:42 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:42 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:42 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:42 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:42 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:42 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:42 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:42 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:42 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:42 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:42 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:42 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:42 +0200] POST /
>d [15/Jun/2007:16:56:42 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:42 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:56:42 +0200] cupsdReadClient: 8, used=279, file=-1 state=6
>d [15/Jun/2007:16:56:42 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:56:42 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4002
>D [15/Jun/2007:16:56:42 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:56:42 +0200] get_printers(0x809c0d48[8], 0)
>d [15/Jun/2007:16:56:42 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:56:42 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:42 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:42 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:42 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:42 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:42 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:42 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:42 +0200] copy_attrs(to=0x809c4560, from=0x809a46c8, ra=0x809c46d0, group=0, quickcopy=0)
>d [15/Jun/2007:16:56:42 +0200] copy_attribute(0x809c4560, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:42 +0200] copy_attribute(0x809c4560, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:42 +0200] copy_attribute(0x809c4560, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:42 +0200] copy_attrs(to=0x809c4560, from=0x80994030, ra=0x809c46d0, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:56:42 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:42 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:56:42 +0200] select_timeout: 27 seconds to send browse update
>d [15/Jun/2007:16:56:42 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:56:42 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:56:42 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:42 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:42 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:56:42 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:43 +0200] select_timeout: 26 seconds to send browse update
>d [15/Jun/2007:16:56:45 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:56:45 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:45 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:56:45 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:56:45 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:56:45 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:45 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:56:45 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:45 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:45 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:45 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:45 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:45 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:45 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:45 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:45 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:45 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:45 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:45 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:45 +0200] POST /
>d [15/Jun/2007:16:56:45 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:45 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:56:45 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4002
>D [15/Jun/2007:16:56:45 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:56:45 +0200] get_printers(0x809c0d48[8], 0)
>d [15/Jun/2007:16:56:45 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:56:45 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:45 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:45 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:45 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:45 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:45 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:45 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:45 +0200] copy_attrs(to=0x809c4560, from=0x809a46c8, ra=0x809c46d0, group=0, quickcopy=0)
>d [15/Jun/2007:16:56:45 +0200] copy_attribute(0x809c4560, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:45 +0200] copy_attribute(0x809c4560, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:45 +0200] copy_attribute(0x809c4560, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:45 +0200] copy_attrs(to=0x809c4560, from=0x80994030, ra=0x809c46d0, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:56:45 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:45 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:56:45 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:56:45 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:56:45 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:45 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:45 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:56:45 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:46 +0200] select_timeout: 23 seconds to send browse update
>d [15/Jun/2007:16:56:48 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:56:48 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:48 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:56:48 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:56:48 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:56:48 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:56:48 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:48 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:56:48 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:48 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:48 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:48 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:48 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:48 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:48 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:48 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:48 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:48 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:48 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:48 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:48 +0200] POST /
>d [15/Jun/2007:16:56:48 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:48 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:56:48 +0200] cupsdReadClient: 8, used=279, file=-1 state=6
>d [15/Jun/2007:16:56:48 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:56:48 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4002
>D [15/Jun/2007:16:56:48 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:56:48 +0200] get_printers(0x809c0d48[8], 0)
>d [15/Jun/2007:16:56:48 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:56:48 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:48 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:48 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:48 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:48 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:48 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:48 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:48 +0200] copy_attrs(to=0x809c4560, from=0x809a46c8, ra=0x809c46d0, group=0, quickcopy=0)
>d [15/Jun/2007:16:56:48 +0200] copy_attribute(0x809c4560, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:48 +0200] copy_attribute(0x809c4560, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:48 +0200] copy_attribute(0x809c4560, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:48 +0200] copy_attrs(to=0x809c4560, from=0x80994030, ra=0x809c46d0, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:56:48 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:48 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:56:48 +0200] select_timeout: 21 seconds to send browse update
>d [15/Jun/2007:16:56:48 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:56:48 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:56:48 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:48 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:48 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:56:48 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:49 +0200] select_timeout: 20 seconds to send browse update
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:54 +0200] POST /
>d [15/Jun/2007:16:56:54 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=105, con->file=-1
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8, used=97, file=-1 state=6
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=97, con->file=-1
>d [15/Jun/2007:16:56:54 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4001
>D [15/Jun/2007:16:56:54 +0200] CUPS-Get-Default
>d [15/Jun/2007:16:56:54 +0200] get_default(0x809c0d48[8])
>d [15/Jun/2007:16:56:54 +0200] cupsdFindPolicyOp(p=0x80999770, op=4001(CUPS-Get-Default))
>d [15/Jun/2007:16:56:54 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:54 +0200] add_printer_state_reasons(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:54 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:54 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:54 +0200] copy_attrs(to=0x809c4560, from=0x809a46c8, ra=(nil), group=0, quickcopy=0)
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a46f0[uri-authentication-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4780[uri-security-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a48e8[printer-more-info,4,45])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4938[job-quota-period,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4988[job-k-limit,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a49d0[job-page-limit,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4a20[job-sheets-default,4,42])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4a98[device-uri,4,45])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809b2cd0[color-supported,4,22])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809abb30[pages-per-minute,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809abba8[printer-make-and-model,4,41])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a5938[media-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a5e28[media-default,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a6098[port-monitor,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a60e8[port-monitor-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4ad0[finishings-supported,4,23])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4b28[finishings-default,4,23])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a8eb8[document-format-supported,4,49])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a9038[copies-default,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a9088[job-hold-until-default,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a90c0[job-priority-default,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a8640[number-up-default,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a8690[orientation-requested-default,4,23])
>d [15/Jun/2007:16:56:54 +0200] copy_attrs(to=0x809c4560, from=0x80994030, ra=(nil), group=0, quickcopy=-2147483648)
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4670[charset-configured,4,47])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a58d8[charset-supported,4,47])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x80994738[compression-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4608[copies-supported,4,33])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4538[document-format-default,4,49])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a44e0[generated-natural-language-supported,4,48])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809947b8[ipp-versions-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x80994340[job-hold-until-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a44b8[job-priority-supported,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x80994548[job-sheets-supported,4,42])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809942a0[multiple-document-handling-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a41d0[multiple-document-jobs-supported,4,22])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a3a48[multiple-operation-time-out,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a39e8[natural-language-configured,4,48])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a45b8[notify-attributes-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a3988[notify-lease-duration-default,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a3930[notify-lease-duration-supported,4,33])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a37e0[notify-max-events-supported,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x80994820[notify-events-default,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a3b20[notify-events-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x80994858[notify-pull-method-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a3880[notify-schemes-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4218[number-up-supported,4,21])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a42a0[operations-supported,4,23])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a8e70[orientation-requested-supported,4,23])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809942f8[page-ranges-supported,4,22])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x80994120[pdl-override-supported,4,44])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809941c0[printer-error-policy-supported,4,42])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x80994090[printer-op-policy-supported,4,42])
>D [15/Jun/2007:16:56:54 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:54 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:56:54 +0200] select_timeout: 15 seconds to send browse update
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 1
>D [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: 10 from localhost (Domain)
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: 10 connected to server on localhost:631
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: Adding fd 10 to InputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:54 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:56:54 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 10, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 10 POST / HTTP/1.1
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:54 +0200] POST /
>d [15/Jun/2007:16:56:54 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 10 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 10, used=279, file=-1 state=6
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 10 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:56:54 +0200] cupsdProcessIPPRequest(0x809c7840[10]): operation_id = 4002
>D [15/Jun/2007:16:56:54 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:56:54 +0200] get_printers(0x809c7840[10], 0)
>d [15/Jun/2007:16:56:54 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:56:54 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:54 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:54 +0200] add_queued_job_count(0x809c7840[10], 0x80993648[Hp])
>d [15/Jun/2007:16:56:54 +0200] copy_attrs(to=0x809c4560, from=0x809a46c8, ra=0x809c46d0, group=0, quickcopy=0)
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:54 +0200] copy_attribute(0x809c4560, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:54 +0200] copy_attrs(to=0x809c4560, from=0x80994030, ra=0x809c46d0, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:56:54 +0200] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:54 +0200] cupsdProcessIPPRequest: Adding fd 10 to OutputSet...
>d [15/Jun/2007:16:56:54 +0200] select_timeout: 15 seconds to send browse update
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: 10 bytes < 0
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: Removing fd 10 from OutputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient(lis=0x80995298) 3 Clients = 1
>D [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: 8 from localhost:631 (IPv4)
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: 8 connected to server on localhost.localdomain:631
>d [15/Jun/2007:16:56:54 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 10, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:54 +0200] cupsdCloseClient: 10
>d [15/Jun/2007:16:56:54 +0200] cupsdCloseClient: Removing fd 10 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8 GET /printers/Hp.ppd HTTP/1.1
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: uri = "/printers/Hp"...
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:54 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: con->uri="/printers/Hp.ppd", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:54 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: con->uri="/printers/Hp.ppd", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:54 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:54 +0200] get_file: 8 filename=/etc/cups/ppd/Hp.ppd size=19747
>d [15/Jun/2007:16:56:54 +0200] is_cgi(con=0x809c0d48, filename="/etc/cups/ppd/Hp.ppd", filestats=0xbf8a9b44, type=application/vnd.cups-ppd)
>d [15/Jun/2007:16:56:54 +0200] is_cgi: Returning 0...
>D [15/Jun/2007:16:56:54 +0200] write_file: 8 file=10
>d [15/Jun/2007:16:56:54 +0200] write_file: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: Read 16384 bytes from file 10...
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: Read 3363 bytes from file 10...
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:56:54 +0200] cupsdWriteClient: 8 Closing data file 10.
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:54 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:54 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:56:54 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:55 +0200] select_timeout: 14 seconds to send browse update
>d [15/Jun/2007:16:56:57 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:56:57 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:56:57 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:56:57 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:56:57 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:56:57 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:56:57 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:56:57 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:56:57 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:56:57 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:56:57 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:56:57 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:56:57 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:56:57 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:57 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:56:57 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:56:57 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:56:57 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:57 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:57 +0200] POST /
>d [15/Jun/2007:16:56:57 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:56:57 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:56:57 +0200] cupsdReadClient: 8, used=279, file=-1 state=6
>d [15/Jun/2007:16:56:57 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:56:57 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4002
>D [15/Jun/2007:16:56:57 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:56:57 +0200] get_printers(0x809c0d48[8], 0)
>d [15/Jun/2007:16:56:57 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:56:57 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:56:57 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:56:57 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:56:57 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:56:57 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:56:57 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:56:57 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:56:57 +0200] copy_attrs(to=0x809c4568, from=0x809a46c8, ra=0x809c46e8, group=0, quickcopy=0)
>d [15/Jun/2007:16:56:57 +0200] copy_attribute(0x809c4568, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:56:57 +0200] copy_attribute(0x809c4568, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:56:57 +0200] copy_attribute(0x809c4568, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:56:57 +0200] copy_attrs(to=0x809c4568, from=0x80994030, ra=0x809c46e8, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:56:57 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:56:57 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:56:57 +0200] select_timeout: 12 seconds to send browse update
>d [15/Jun/2007:16:56:57 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:56:57 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:56:57 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:56:57 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:56:57 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:56:57 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:56:58 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:56:58 +0200] select_timeout: 11 seconds to send browse update
>d [15/Jun/2007:16:57:00 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:00 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:00 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:57:00 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:57:00 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:57:00 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:00 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:57:00 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:00 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:00 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:00 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:00 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:00 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:00 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:00 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:00 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:00 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:00 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:00 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:00 +0200] POST /
>d [15/Jun/2007:16:57:00 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:00 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:57:00 +0200] cupsdReadClient: 8, used=279, file=-1 state=6
>d [15/Jun/2007:16:57:00 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:57:00 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4002
>D [15/Jun/2007:16:57:00 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:57:00 +0200] get_printers(0x809c0d48[8], 0)
>d [15/Jun/2007:16:57:00 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:57:00 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:00 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:00 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:00 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:00 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:00 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:57:00 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:57:00 +0200] copy_attrs(to=0x809c4568, from=0x809a46c8, ra=0x809c46e8, group=0, quickcopy=0)
>d [15/Jun/2007:16:57:00 +0200] copy_attribute(0x809c4568, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:57:00 +0200] copy_attribute(0x809c4568, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:57:00 +0200] copy_attribute(0x809c4568, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:57:00 +0200] copy_attrs(to=0x809c4568, from=0x80994030, ra=0x809c46e8, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:57:00 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:00 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:57:00 +0200] select_timeout: 9 seconds to send browse update
>d [15/Jun/2007:16:57:00 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:57:00 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:57:00 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:00 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:00 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:57:00 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:01 +0200] select_timeout: 8 seconds to send browse update
>d [15/Jun/2007:16:57:03 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:03 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:03 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:57:03 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:57:03 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:57:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:03 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:57:03 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:03 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:03 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:03 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:03 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:03 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:03 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:03 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:03 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:03 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:03 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:03 +0200] POST /
>d [15/Jun/2007:16:57:03 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:03 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:57:03 +0200] cupsdReadClient: 8, used=279, file=-1 state=6
>d [15/Jun/2007:16:57:03 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:57:03 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4002
>D [15/Jun/2007:16:57:03 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:57:03 +0200] get_printers(0x809c0d48[8], 0)
>d [15/Jun/2007:16:57:03 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:57:03 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:03 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:03 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:03 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:03 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:57:03 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:57:03 +0200] copy_attrs(to=0x809c4568, from=0x809a46c8, ra=0x809c46e8, group=0, quickcopy=0)
>d [15/Jun/2007:16:57:03 +0200] copy_attribute(0x809c4568, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:57:03 +0200] copy_attribute(0x809c4568, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:57:03 +0200] copy_attribute(0x809c4568, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:57:03 +0200] copy_attrs(to=0x809c4568, from=0x80994030, ra=0x809c46e8, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:57:03 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:03 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:57:03 +0200] select_timeout: 6 seconds to send browse update
>d [15/Jun/2007:16:57:03 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:57:03 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:57:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:03 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:03 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:57:03 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:04 +0200] select_timeout: 5 seconds to send browse update
>d [15/Jun/2007:16:57:06 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:06 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:06 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:57:06 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:57:06 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:57:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:06 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:57:06 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:06 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:06 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:06 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:06 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:06 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:06 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:06 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:06 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:06 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:06 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:06 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:06 +0200] POST /
>d [15/Jun/2007:16:57:06 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:06 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=287, con->file=-1
>d [15/Jun/2007:16:57:06 +0200] cupsdReadClient: 8, used=279, file=-1 state=6
>d [15/Jun/2007:16:57:06 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=279, con->file=-1
>d [15/Jun/2007:16:57:06 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 4002
>D [15/Jun/2007:16:57:06 +0200] CUPS-Get-Printers
>d [15/Jun/2007:16:57:06 +0200] get_printers(0x809c0d48[8], 0)
>d [15/Jun/2007:16:57:06 +0200] cupsdFindPolicyOp(p=0x80999770, op=4002(CUPS-Get-Printers))
>d [15/Jun/2007:16:57:06 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:06 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:06 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:06 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:06 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:06 +0200] printer-uri-supported="ipp://localhost:631/printers/Hp"
>d [15/Jun/2007:16:57:06 +0200] add_queued_job_count(0x809c0d48[8], 0x80993648[Hp])
>d [15/Jun/2007:16:57:06 +0200] copy_attrs(to=0x809c4568, from=0x809a46c8, ra=0x809c46e8, group=0, quickcopy=0)
>d [15/Jun/2007:16:57:06 +0200] copy_attribute(0x809c4568, 0x809a47d8[printer-name,4,42])
>d [15/Jun/2007:16:57:06 +0200] copy_attribute(0x809c4568, 0x809a4828[printer-location,4,41])
>d [15/Jun/2007:16:57:06 +0200] copy_attribute(0x809c4568, 0x809a4898[printer-info,4,41])
>d [15/Jun/2007:16:57:06 +0200] copy_attrs(to=0x809c4568, from=0x80994030, ra=0x809c46e8, group=0, quickcopy=-2147483648)
>D [15/Jun/2007:16:57:06 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:06 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:57:06 +0200] select_timeout: 3 seconds to send browse update
>d [15/Jun/2007:16:57:06 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:57:06 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:57:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:06 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:06 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:57:06 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:07 +0200] stringpool: 328 strings, 6816 allocated, 6432 total bytes
>d [15/Jun/2007:16:57:07 +0200] select_timeout: 2 seconds to send browse update
>d [15/Jun/2007:16:57:09 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:57:09 +0200] select_timeout: 31 seconds to send browse update
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 POST /printers/Hp HTTP/1.1
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: uri = "/printers/Hp"...
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: con->uri="/printers/Hp", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/printers/Hp", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] POST /printers/Hp
>d [15/Jun/2007:16:57:10 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=420763, con->file=-1
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=310, file=-1 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=420755, con->file=-1
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 REQUEST /var/spool/cups/00000000=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] select_timeout: 30 seconds to send browse update
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=412253, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=404061, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=395869, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=387677, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=379485, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=371293, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=363101, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=354909, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=346717, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=338525, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=330333, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=322141, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=313949, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=305757, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=297565, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=289373, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=281181, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=272989, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=264797, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=256605, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=248413, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=240221, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=232029, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=223837, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=215645, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=207453, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=199261, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=191069, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=182877, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=174685, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=166493, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=158301, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=150109, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=141917, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=133725, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=125533, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=117341, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=109149, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=100957, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=92765, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=84573, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=76381, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=68189, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=59997, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=51805, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=43613, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=35421, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=27229, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=19037, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=10845, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 8192 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=10 state=6
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=2653, con->file=10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 writing 2653 bytes to 10
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 Closing data file 10, size=420445.
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 0002
>D [15/Jun/2007:16:57:10 +0200] Print-Job ipp://localhost:631/printers/Hp
>d [15/Jun/2007:16:57:10 +0200] print_job(0x809c0d48[8], ipp://localhost:631/printers/Hp)
>D [15/Jun/2007:16:57:10 +0200] print_job: auto-typing file...
>D [15/Jun/2007:16:57:10 +0200] print_job: request file type is application/postscript.
>d [15/Jun/2007:16:57:10 +0200] add_job(0x809c0d48[8], ipp://localhost:631/printers/Hp)
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp(p=0x80999770, op=2(Print-Job))
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/printers/Hp", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] check_quotas(0x809c0d48[8], 0x80993648[Hp])
>D [15/Jun/2007:16:57:10 +0200] add_job: setting context of job to UNKNOWN SL
>D [15/Jun/2007:16:57:10 +0200] add_job: requesting-user-name="nicko"
>I [15/Jun/2007:16:57:10 +0200] Adding start banner page "none" to job 12.
>d [15/Jun/2007:16:57:10 +0200] copy_banner(0x809c0d48[8], 0x809c4888[12], none)
>d [15/Jun/2007:16:57:10 +0200] add_job_state_reasons(0x809c0d48[8], 12)
>d [15/Jun/2007:16:57:10 +0200] cupsdAddEvent(event=job-created, dest=0x80993648(Hp), job=0x809c4888(12), text="Job created.", ...)
>D [15/Jun/2007:16:57:10 +0200] Discarding unused job-created event...
>d [15/Jun/2007:16:57:10 +0200] add_file(con=0x809c0d48[8], job=12, filetype=application/postscript, compression=0)
>I [15/Jun/2007:16:57:10 +0200] Adding end banner page "none" to job 12.
>d [15/Jun/2007:16:57:10 +0200] copy_banner(0x809c0d48[8], 0x809c4888[12], none)
>I [15/Jun/2007:16:57:10 +0200] Job 12 queued on "Hp" by "nicko".
>D [15/Jun/2007:16:57:10 +0200] Job 12 hold_until = 0
>d [15/Jun/2007:16:57:10 +0200] cupsdSaveJob(job=0x809c4888(12)): job->attrs=0x809c4490
>d [15/Jun/2007:16:57:10 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:57:10 +0200] cupsdCheckJobs: Job 12: state_value=3, loaded=yes
>d [15/Jun/2007:16:57:10 +0200] start_job: id = 12, file = 0/1
>d [15/Jun/2007:16:57:10 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80993648(Hp), job=(nil)(0), text="%s "%s" state changed.", ...)
>D [15/Jun/2007:16:57:10 +0200] Discarding unused printer-state-changed event...
>D [15/Jun/2007:16:57:10 +0200] job-sheets=none,none
>D [15/Jun/2007:16:57:10 +0200] banner_page = 0
>D [15/Jun/2007:16:57:10 +0200] [Job 12] argv[0]="Hp"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] argv[1]="12"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] argv[2]="nicko"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] argv[3]="blaus_mascarino.jpg"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] argv[4]="1"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] argv[5]="PrintoutMode=Normal PageSize=A4 Quality=FromPrintoutMode number-up=1 job-uuid=urn:uuid:aea50d7f-bc77-3546-71e4-cd0538330e76"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] argv[6]="/var/spool/cups/d00012-001"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups-1.2.10"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[9]="SERVER_ADMIN=root@localhost.localdomain"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[10]="SOFTWARE=CUPS/1.2.10"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[11]="TMPDIR=/var/spool/cups/tmp"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[12]="USER=root"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[14]="CUPS_ENCRYPTION=IfRequested"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[15]="IPP_PORT=631"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[16]="CHARSET=utf-8"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[17]="LANG=it_IT"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[18]="PPD=/etc/cups/ppd/Hp.ppd"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[19]="RIP_MAX_CACHE=8m"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[20]="CONTENT_TYPE=application/postscript"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[21]="DEVICE_URI=http://192.168.1.1:1631/printers/Hpdeskjet845c"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[22]="PRINTER=Hp"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] envp[23]="FINAL_CONTENT_TYPE=printer/Hp"
>d [15/Jun/2007:16:57:10 +0200] start_job: status_pipes = [ 13 14 ]
>d [15/Jun/2007:16:57:10 +0200] start_job: filter="/usr/lib/cups/filter/pstops"
>d [15/Jun/2007:16:57:10 +0200] start_job: filterfds[0]=[ 15 16 ]
>d [15/Jun/2007:16:57:10 +0200] cupsdStartProcess("/usr/lib/cups/filter/pstops", 0x809c9590, 0xbf8a53e8, -1, 16, 14)
>d [15/Jun/2007:16:57:10 +0200] start_job: Closing filter pipes for slot 1 [ -1 -1 ]...
>I [15/Jun/2007:16:57:10 +0200] Started filter /usr/lib/cups/filter/pstops (PID 2529) for job 12.
>d [15/Jun/2007:16:57:10 +0200] start_job: print_pipes = [ 17 18 ]
>d [15/Jun/2007:16:57:10 +0200] start_job: filter="/usr/lib/cups/filter/foomatic-rip"
>d [15/Jun/2007:16:57:10 +0200] start_job: filterfds[1]=[ 17 18 ]
>d [15/Jun/2007:16:57:10 +0200] cupsdStartProcess("/usr/lib/cups/filter/foomatic-rip", 0x809c9590, 0xbf8a53e8, 15, 18, 14)
>d [15/Jun/2007:16:57:10 +0200] start_job: Closing filter pipes for slot 0 [ 15 16 ]...
>I [15/Jun/2007:16:57:10 +0200] Started filter /usr/lib/cups/filter/foomatic-rip (PID 2530) for job 12.
>d [15/Jun/2007:16:57:10 +0200] start_job: backend="/usr/lib/cups/backend/http"
>d [15/Jun/2007:16:57:10 +0200] start_job: filterfds[0] = [ -1 -1 ]
>d [15/Jun/2007:16:57:10 +0200] cupsdStartProcess("/usr/lib/cups/backend/http", 0x809c9590, 0xbf8a53e8, 17, -1, 14)
>I [15/Jun/2007:16:57:10 +0200] Started backend /usr/lib/cups/backend/http (PID 2531) for job 12.
>d [15/Jun/2007:16:57:10 +0200] start_job: Closing print pipes [ 17 18 ]...
>d [15/Jun/2007:16:57:10 +0200] start_job: Closing back pipes [ 11 12 ]...
>d [15/Jun/2007:16:57:10 +0200] start_job: Closing status output pipe 14...
>d [15/Jun/2007:16:57:10 +0200] start_job: Closing filter pipes for slot 0 [ -1 -1 ]...
>d [15/Jun/2007:16:57:10 +0200] start_job: Adding fd 13 to InputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdAddEvent(event=job-state, dest=0x80993648(Hp), job=0x809c4888(12), text="Job #%d started.", ...)
>D [15/Jun/2007:16:57:10 +0200] Discarding unused job-state event...
>D [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Page = 595x842; 10,36 to 585,833
>D [15/Jun/2007:16:57:10 +0200] [Job 12] slow_collate=0, slow_duplex=0, slow_order=0
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Before copy_comments - %!PS-Adobe-3.0
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %!PS-Adobe-3.0
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %%Creator: cairo 1.4.4 (http://cairographics.org)
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %%CreationDate: Fri Jun 15 16:57:10 2007
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %%Pages: 1
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %%BoundingBox: 0 0 596 842
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %%DocumentData: Clean7Bit
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %%LanguageLevel: 2
>D [15/Jun/2007:16:57:10 +0200] [Job 12] %%EndComments
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Before copy_prolog - %%BeginProlog
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Before copy_setup - % _cairo_ps_surface_emit_font_subsets
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Before page loop - %%Page: 1 1
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Copying page 1...
>D [15/Jun/2007:16:57:10 +0200] [Job 12] pagew = 575.6, pagel = 797.0
>D [15/Jun/2007:16:57:10 +0200] [Job 12] bboxw = 595, bboxl = 842
>D [15/Jun/2007:16:57:10 +0200] [Job 12] PageLeft = 9.7, PageRight = 585.3
>D [15/Jun/2007:16:57:10 +0200] [Job 12] PageTop = 833.0, PageBottom = 36.0
>D [15/Jun/2007:16:57:10 +0200] [Job 12] PageWidth = 595.0, PageLength = 842.0
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 1
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 11 from localhost (Domain)
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 11 connected to server on localhost:631
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: Adding fd 11 to InputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11 POST / HTTP/1.1
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] POST /
>d [15/Jun/2007:16:57:10 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest(0x809c9b50[11]): operation_id = 000a
>D [15/Jun/2007:16:57:10 +0200] Get-Jobs ipp://localhost/jobs/
>d [15/Jun/2007:16:57:10 +0200] get_jobs(0x809c9b50[11], ipp://localhost/jobs/)
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp(p=0x80999770, op=a(Get-Jobs))
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] get_jobs: job->id = 12
>d [15/Jun/2007:16:57:10 +0200] get_jobs: count = 1
>d [15/Jun/2007:16:57:10 +0200] add_job_state_reasons(0x809c9b50[11], 12)
>d [15/Jun/2007:16:57:10 +0200] copy_attrs(to=0x809cd378, from=0x809c4490, ra=(nil), group=2, quickcopy=0)
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4520[job-originating-user-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4568[printer-uri,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c45b8[job-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c45f8[PrintoutMode,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4638[PageSize,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4680[job-priority,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c46c0[Quality,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4700[job-sheets,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4788[number-up,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4848[document-format,2,49])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4ae8[job-uuid,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809c4b48[job-originating-host-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3c68[time-at-creation,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3ca8[time-at-processing,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3ce8[time-at-completed,2,13])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3d28[job-id,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3d50[job-state,2,23])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3d78[job-media-sheets-completed,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3dc0[job-printer-uri,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3e00[job-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3e28[job-k-octets,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809cd378, 0x809a3e68[job-hold-until,2,44])
>D [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: Adding fd 11 to OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: 11 bytes < 0
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: Removing fd 11 from OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 1
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: 11
>d [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: Removing fd 11 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] POST /
>d [15/Jun/2007:16:57:10 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest(0x809c0d48[8]): operation_id = 000a
>D [15/Jun/2007:16:57:10 +0200] Get-Jobs ipp://localhost/jobs/
>d [15/Jun/2007:16:57:10 +0200] get_jobs(0x809c0d48[8], ipp://localhost/jobs/)
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp(p=0x80999770, op=a(Get-Jobs))
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] get_jobs: job->id = 12
>d [15/Jun/2007:16:57:10 +0200] get_jobs: count = 1
>d [15/Jun/2007:16:57:10 +0200] add_job_state_reasons(0x809c0d48[8], 12)
>d [15/Jun/2007:16:57:10 +0200] copy_attrs(to=0x809a3ed0, from=0x809c4490, ra=(nil), group=2, quickcopy=0)
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4520[job-originating-user-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4568[printer-uri,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c45b8[job-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c45f8[PrintoutMode,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4638[PageSize,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4680[job-priority,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c46c0[Quality,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4700[job-sheets,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4788[number-up,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4848[document-format,2,49])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4ae8[job-uuid,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4b48[job-originating-host-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3c68[time-at-creation,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3ca8[time-at-processing,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3ce8[time-at-completed,2,13])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3d28[job-id,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3d50[job-state,2,23])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3d78[job-media-sheets-completed,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3dc0[job-printer-uri,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3e00[job-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3e28[job-k-octets,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3e68[job-hold-until,2,44])
>D [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: 8 bytes < 0
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 1
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 11 from localhost (Domain)
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: 11 connected to server on localhost:631
>d [15/Jun/2007:16:57:10 +0200] cupsdAcceptClient: Adding fd 11 to InputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: 8
>d [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11 POST / HTTP/1.1
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:10 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:10 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] POST /
>d [15/Jun/2007:16:57:10 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest(0x809c9b50[11]): operation_id = 000a
>D [15/Jun/2007:16:57:10 +0200] Get-Jobs ipp://localhost/jobs/
>d [15/Jun/2007:16:57:10 +0200] get_jobs(0x809c9b50[11], ipp://localhost/jobs/)
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp(p=0x80999770, op=a(Get-Jobs))
>d [15/Jun/2007:16:57:10 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:10 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:10 +0200] get_jobs: job->id = 12
>d [15/Jun/2007:16:57:10 +0200] get_jobs: count = 1
>d [15/Jun/2007:16:57:10 +0200] add_job_state_reasons(0x809c9b50[11], 12)
>d [15/Jun/2007:16:57:10 +0200] copy_attrs(to=0x809a3ed0, from=0x809c4490, ra=(nil), group=2, quickcopy=0)
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4520[job-originating-user-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4568[printer-uri,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c45b8[job-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c45f8[PrintoutMode,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4638[PageSize,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4680[job-priority,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c46c0[Quality,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4700[job-sheets,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4788[number-up,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4848[document-format,2,49])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4ae8[job-uuid,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809c4b48[job-originating-host-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3c68[time-at-creation,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3ca8[time-at-processing,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3ce8[time-at-completed,2,13])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3d28[job-id,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3d50[job-state,2,23])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3d78[job-media-sheets-completed,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3dc0[job-printer-uri,2,45])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3e00[job-name,2,42])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3e28[job-k-octets,2,21])
>d [15/Jun/2007:16:57:10 +0200] copy_attribute(0x809a3ed0, 0x809a3e68[job-hold-until,2,44])
>D [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:10 +0200] cupsdProcessIPPRequest: Adding fd 11 to OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: 11 bytes < 0
>d [15/Jun/2007:16:57:10 +0200] cupsdWriteClient: Removing fd 11 from OutputSet...
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:10 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: 11
>d [15/Jun/2007:16:57:10 +0200] cupsdCloseClient: Removing fd 11 from InputSet and OutputSet...
>D [15/Jun/2007:16:57:10 +0200] [Job 12] foomatic-rip version $Revision: 3.43.2.15 $ running...
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Parsing PPD file ...
>D [15/Jun/2007:16:57:10 +0200] [Job 12] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option ColorSpace
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option Resolution
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option PageSize
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option PageRegion
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option Model
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option PrintoutMode
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option ImageableArea
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option PaperDimension
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option Quality
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Added option Font
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Parameter Summary
>D [15/Jun/2007:16:57:10 +0200] [Job 12] -----------------
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Spooler: cups
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Printer: Hp
>D [15/Jun/2007:16:57:10 +0200] [Job 12] PPD file: /etc/cups/ppd/Hp.ppd
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Printer model: HP DeskJet 845C Foomatic/hpijs (recommended)
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Job title: blaus_mascarino.jpg
>D [15/Jun/2007:16:57:10 +0200] [Job 12] File(s) to be printed: 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] <STDIN>
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Pondering option 'PrintoutMode=Normal'
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Pondering option 'PageSize=A4'
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Pondering option 'Quality=FromPrintoutMode'
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Pondering option 'number-up=1'
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Unknown option number-up=1.
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Pondering option 'job-uuid=urn:uuid:aea50d7f-bc77-3546-71e4-cd0538330e76'
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Unknown option job-uuid=urn:uuid:aea50d7f-bc77-3546-71e4-cd0538330e76.
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] ================================================
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] File: <STDIN>
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] ================================================
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Reading PostScript input ...
>D [15/Jun/2007:16:57:10 +0200] [Job 12] --> This document is DSC-conforming!
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] -----------
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%BeginProlog
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%EndProlog
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] -----------
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%BeginSetup
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%BeginFeature: *PrintoutMode Normal
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Option: PrintoutMode=Normal --> Setting option
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Option: PrintoutMode=Normal --> Setting option
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%BeginFeature: *Quality FromPrintoutMode
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Option: Quality=FromPrintoutMode --> Setting option
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Option: Quality=FromPrintoutMode --> Setting option
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%BeginFeature: *PageSize A4
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Option: PageSize=A4 --> Setting option
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %% FoomaticRIPOptionSetting: PageSize=A4
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Option: PageSize=A4 --> Setting option
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%EndSetup
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Inserting PostScript code for CUPS' page accounting
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] -----------
>D [15/Jun/2007:16:57:10 +0200] [Job 12] New page:  1 1
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Inserting option code into "PageSetup" section.
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%BeginPageSetup
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found: %%EndPageSetup
>D [15/Jun/2007:16:57:10 +0200] [Job 12] End of page header
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Stopping search for page header options
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Found:
>D [15/Jun/2007:16:57:10 +0200] [Job 12] pO@b-9O7lPL=3$Y6B%lZM2\(k.N$4-*H$o<1.Fmltmm039A9?&PCoH-47N,W]`jZ4Xt$
>D [15/Jun/2007:16:57:10 +0200] [Job 12] --> Output goes directly to the renderer now.
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] Starting renderer
>D [15/Jun/2007:16:57:10 +0200] [Job 12] JCL: <job data> 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] 
>D [15/Jun/2007:16:57:10 +0200] [Job 12] renderer PID kid4=2534
>D [15/Jun/2007:16:57:10 +0200] [Job 12] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="DESKJET 845" -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1 -dIjsUseOutputFD -sOutputFile=- -
>D [15/Jun/2007:16:57:10 +0200] [Job 12] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=DESKJET 845' '-dDEVICEWIDTHPOINTS=595' '-dDEVICEHEIGHTPOINTS=842' '-r300' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1' '-dIjsUseOutputFD' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
>d [15/Jun/2007:16:57:11 +0200] select_timeout: 11 seconds to process active jobs
>D [15/Jun/2007:16:57:12 +0200] [Job 12] Wrote 1 pages...
>d [15/Jun/2007:16:57:12 +0200] process_children()
>D [15/Jun/2007:16:57:12 +0200] PID 2529 (/usr/lib/cups/filter/pstops) exited with no errors.
>D [15/Jun/2007:16:57:12 +0200] [Job 12] 
>D [15/Jun/2007:16:57:12 +0200] [Job 12] Closing renderer
>d [15/Jun/2007:16:57:12 +0200] [Job 12] PAGE: 1 1
>d [15/Jun/2007:16:57:12 +0200] cupsdAddEvent(event=job-progress, dest=0x80993648(Hp), job=0x809c4888(12), text="Printed %d page(s).", ...)
>D [15/Jun/2007:16:57:12 +0200] Discarding unused job-progress event...
>d [15/Jun/2007:16:57:13 +0200] select_timeout: 11 seconds to process active jobs
>D [15/Jun/2007:16:57:14 +0200] [Job 12] tail process done writing data to STDOUT
>D [15/Jun/2007:16:57:14 +0200] [Job 12] KID4 finished
>D [15/Jun/2007:16:57:14 +0200] [Job 12] KID3 finished
>D [15/Jun/2007:16:57:14 +0200] [Job 12] KID3 exited with status 0
>D [15/Jun/2007:16:57:14 +0200] [Job 12] KID4 exited with status 0
>D [15/Jun/2007:16:57:14 +0200] [Job 12] Renderer exit stat: 0
>D [15/Jun/2007:16:57:14 +0200] [Job 12] Renderer process finished
>D [15/Jun/2007:16:57:14 +0200] [Job 12] 
>D [15/Jun/2007:16:57:14 +0200] [Job 12] Closing foomatic-rip.
>d [15/Jun/2007:16:57:14 +0200] process_children()
>D [15/Jun/2007:16:57:14 +0200] PID 2530 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
>D [15/Jun/2007:16:57:14 +0200] [Job 12] 1 files to send in job...
>d [15/Jun/2007:16:57:14 +0200] [Job 12] STATE: +connecting-to-device
>d [15/Jun/2007:16:57:14 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80993648(Hp), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [15/Jun/2007:16:57:14 +0200] Discarding unused printer-state-changed event...
>I [15/Jun/2007:16:57:14 +0200] [Job 12] Connecting to 192.168.1.1 on port 1631...
>d [15/Jun/2007:16:57:14 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80993648(Hp), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [15/Jun/2007:16:57:14 +0200] Discarding unused printer-state-changed event...
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 from localhost (Domain)
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 connected to server on localhost:631
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: Adding fd 11 to InputSet...
>d [15/Jun/2007:16:57:14 +0200] [Job 12] STATE: -connecting-to-device
>d [15/Jun/2007:16:57:14 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80993648(Hp), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [15/Jun/2007:16:57:14 +0200] Discarding unused printer-state-changed event...
>I [15/Jun/2007:16:57:14 +0200] [Job 12] Connected to 192.168.1.1...
>d [15/Jun/2007:16:57:14 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80993648(Hp), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [15/Jun/2007:16:57:14 +0200] Discarding unused printer-state-changed event...
>D [15/Jun/2007:16:57:14 +0200] [Job 12] Connected to 192.168.1.1:1631 (IPv4)...
>D [15/Jun/2007:16:57:14 +0200] [Job 12] Getting supported attributes...
>D [15/Jun/2007:16:57:14 +0200] [Job 12] document-format-supported (1 values)
>D [15/Jun/2007:16:57:14 +0200] [0] = "application/octet-stream"
>D [15/Jun/2007:16:57:14 +0200] [Job 12] printer-uri = "http://192.168.1.1:1631/printers/Hpdeskjet845c"
>D [15/Jun/2007:16:57:14 +0200] [Job 12] requesting-user-name = "nicko"
>D [15/Jun/2007:16:57:14 +0200] [Job 12] job-name = "blaus_mascarino.jpg"
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 POST / HTTP/1.1
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] POST /
>d [15/Jun/2007:16:57:14 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=171, file=-1 state=6
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=171, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest(0x809c9b50[11]): operation_id = 000a
>D [15/Jun/2007:16:57:14 +0200] Get-Jobs ipp://localhost/jobs/
>d [15/Jun/2007:16:57:14 +0200] get_jobs(0x809c9b50[11], ipp://localhost/jobs/)
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp(p=0x80999770, op=a(Get-Jobs))
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] get_jobs: job->id = 12
>d [15/Jun/2007:16:57:14 +0200] get_jobs: count = 1
>d [15/Jun/2007:16:57:14 +0200] add_job_state_reasons(0x809c9b50[11], 12)
>d [15/Jun/2007:16:57:14 +0200] copy_attrs(to=0x809c31f0, from=0x809c4490, ra=(nil), group=2, quickcopy=0)
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4520[job-originating-user-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4568[printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45b8[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45f8[PrintoutMode,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4638[PageSize,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4680[job-priority,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c46c0[Quality,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4700[job-sheets,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4788[number-up,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4848[document-format,2,49])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4ae8[job-uuid,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4b48[job-originating-host-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3c68[time-at-creation,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ca8[time-at-processing,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ce8[time-at-completed,2,13])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d28[job-id,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d50[job-state,2,23])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d78[job-media-sheets-completed,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3dc0[job-printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e00[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e28[job-k-octets,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e68[job-hold-until,2,44])
>D [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: Adding fd 11 to OutputSet...
>d [15/Jun/2007:16:57:14 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: 11 bytes < 0
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: Removing fd 11 from OutputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: 11
>d [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: Removing fd 11 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 from localhost (Domain)
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 connected to server on localhost:631
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: Adding fd 11 to InputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 POST / HTTP/1.1
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] POST /
>d [15/Jun/2007:16:57:14 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=171, file=-1 state=6
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=171, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest(0x809c9b50[11]): operation_id = 000a
>D [15/Jun/2007:16:57:14 +0200] Get-Jobs ipp://localhost/jobs/
>d [15/Jun/2007:16:57:14 +0200] get_jobs(0x809c9b50[11], ipp://localhost/jobs/)
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp(p=0x80999770, op=a(Get-Jobs))
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] get_jobs: job->id = 12
>d [15/Jun/2007:16:57:14 +0200] get_jobs: count = 1
>d [15/Jun/2007:16:57:14 +0200] add_job_state_reasons(0x809c9b50[11], 12)
>d [15/Jun/2007:16:57:14 +0200] copy_attrs(to=0x809c31f0, from=0x809c4490, ra=(nil), group=2, quickcopy=0)
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4520[job-originating-user-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4568[printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45b8[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45f8[PrintoutMode,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4638[PageSize,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4680[job-priority,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c46c0[Quality,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4700[job-sheets,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4788[number-up,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4848[document-format,2,49])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4ae8[job-uuid,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4b48[job-originating-host-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3c68[time-at-creation,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ca8[time-at-processing,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ce8[time-at-completed,2,13])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d28[job-id,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d50[job-state,2,23])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d78[job-media-sheets-completed,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3dc0[job-printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e00[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e28[job-k-octets,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e68[job-hold-until,2,44])
>D [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: Adding fd 11 to OutputSet...
>d [15/Jun/2007:16:57:14 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: 11 bytes < 0
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: Removing fd 11 from OutputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: 11
>d [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: Removing fd 11 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 from localhost (Domain)
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 connected to server on localhost:631
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: Adding fd 11 to InputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 POST / HTTP/1.1
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] POST /
>d [15/Jun/2007:16:57:14 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=171, file=-1 state=6
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=171, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest(0x809c9b50[11]): operation_id = 000a
>D [15/Jun/2007:16:57:14 +0200] Get-Jobs ipp://localhost/jobs/
>d [15/Jun/2007:16:57:14 +0200] get_jobs(0x809c9b50[11], ipp://localhost/jobs/)
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp(p=0x80999770, op=a(Get-Jobs))
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] get_jobs: job->id = 12
>d [15/Jun/2007:16:57:14 +0200] get_jobs: count = 1
>d [15/Jun/2007:16:57:14 +0200] add_job_state_reasons(0x809c9b50[11], 12)
>d [15/Jun/2007:16:57:14 +0200] copy_attrs(to=0x809c31f0, from=0x809c4490, ra=(nil), group=2, quickcopy=0)
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4520[job-originating-user-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4568[printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45b8[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45f8[PrintoutMode,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4638[PageSize,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4680[job-priority,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c46c0[Quality,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4700[job-sheets,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4788[number-up,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4848[document-format,2,49])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4ae8[job-uuid,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4b48[job-originating-host-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3c68[time-at-creation,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ca8[time-at-processing,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ce8[time-at-completed,2,13])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d28[job-id,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d50[job-state,2,23])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d78[job-media-sheets-completed,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3dc0[job-printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e00[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e28[job-k-octets,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e68[job-hold-until,2,44])
>D [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: Adding fd 11 to OutputSet...
>d [15/Jun/2007:16:57:14 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: 11 bytes < 0
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: Removing fd 11 from OutputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: 11
>d [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: Removing fd 11 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient(lis=0x80999450) 4 Clients = 0
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: skipping getpeercon()
>D [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 from localhost (Domain)
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: 11 connected to server on localhost:631
>d [15/Jun/2007:16:57:14 +0200] cupsdAcceptClient: Adding fd 11 to InputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>D [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 POST / HTTP/1.1
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: uri = "/"...
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location /admin Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: Location / Limit 7f
>d [15/Jun/2007:16:57:14 +0200] cupsdFindBest: best = /
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: Authorization=""
>D [15/Jun/2007:16:57:14 +0200] cupsdAuthorize: No authentication data provided.
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x809995a8(/)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] POST /
>d [15/Jun/2007:16:57:14 +0200] CONTENT_TYPE = application/ipp
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=171, file=-1 state=6
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=171, con->file=-1
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest(0x809c9b50[11]): operation_id = 000a
>D [15/Jun/2007:16:57:14 +0200] Get-Jobs ipp://localhost/jobs/
>d [15/Jun/2007:16:57:14 +0200] get_jobs(0x809c9b50[11], ipp://localhost/jobs/)
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp(p=0x80999770, op=a(Get-Jobs))
>d [15/Jun/2007:16:57:14 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8099a9c0((null))
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [15/Jun/2007:16:57:14 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [15/Jun/2007:16:57:14 +0200] get_jobs: job->id = 12
>d [15/Jun/2007:16:57:14 +0200] get_jobs: count = 1
>d [15/Jun/2007:16:57:14 +0200] add_job_state_reasons(0x809c9b50[11], 12)
>d [15/Jun/2007:16:57:14 +0200] copy_attrs(to=0x809c31f0, from=0x809c4490, ra=(nil), group=2, quickcopy=0)
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4520[job-originating-user-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4568[printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45b8[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c45f8[PrintoutMode,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4638[PageSize,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4680[job-priority,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c46c0[Quality,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4700[job-sheets,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4788[number-up,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4848[document-format,2,49])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4ae8[job-uuid,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809c4b48[job-originating-host-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3c68[time-at-creation,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ca8[time-at-processing,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3ce8[time-at-completed,2,13])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d28[job-id,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d50[job-state,2,23])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3d78[job-media-sheets-completed,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3dc0[job-printer-uri,2,45])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e00[job-name,2,42])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e28[job-k-octets,2,21])
>d [15/Jun/2007:16:57:14 +0200] copy_attribute(0x809c31f0, 0x809a3e68[job-hold-until,2,44])
>D [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
>d [15/Jun/2007:16:57:14 +0200] cupsdProcessIPPRequest: Adding fd 11 to OutputSet...
>d [15/Jun/2007:16:57:14 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: 11 bytes < 0
>d [15/Jun/2007:16:57:14 +0200] cupsdWriteClient: Removing fd 11 from OutputSet...
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>d [15/Jun/2007:16:57:14 +0200] cupsdReadClient: httpGets returned EOF...
>D [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: 11
>d [15/Jun/2007:16:57:14 +0200] cupsdCloseClient: Removing fd 11 from InputSet and OutputSet...
>d [15/Jun/2007:16:57:15 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:57:26 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:57:26 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:57:26 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:57:37 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:57:37 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:57:37 +0200] select_timeout: 5 seconds to send browse update
>d [15/Jun/2007:16:57:42 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:57:53 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:57:53 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:57:53 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:58:04 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:58:04 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:58:04 +0200] select_timeout: 9 seconds to send browse update
>d [15/Jun/2007:16:58:13 +0200] stringpool: 424 strings, 7792 allocated, 8496 total bytes
>d [15/Jun/2007:16:58:13 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:58:24 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:58:24 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:58:24 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:58:35 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:58:35 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:58:35 +0200] select_timeout: 9 seconds to send browse update
>d [15/Jun/2007:16:58:44 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:58:55 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:58:55 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:58:55 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:59:06 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:59:06 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:59:06 +0200] select_timeout: 9 seconds to send browse update
>d [15/Jun/2007:16:59:15 +0200] stringpool: 424 strings, 7792 allocated, 8496 total bytes
>d [15/Jun/2007:16:59:15 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:59:26 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:59:26 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:59:26 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:59:37 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:59:37 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:59:37 +0200] select_timeout: 9 seconds to send browse update
>d [15/Jun/2007:16:59:46 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:16:59:57 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:16:59:57 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:16:59:57 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:17:00:08 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:17:00:08 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:17:00:08 +0200] select_timeout: 9 seconds to send browse update
>d [15/Jun/2007:17:00:17 +0200] stringpool: 424 strings, 7792 allocated, 8496 total bytes
>d [15/Jun/2007:17:00:17 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:17:00:28 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:17:00:28 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:17:00:28 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:17:00:39 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:17:00:39 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:17:00:39 +0200] select_timeout: 9 seconds to send browse update
>d [15/Jun/2007:17:00:48 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:17:00:59 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:17:00:59 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:17:00:59 +0200] select_timeout: 11 seconds to process active jobs
>d [15/Jun/2007:17:01:10 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [15/Jun/2007:17:01:10 +0200] cupsdCheckJobs: Job 12: state_value=5, loaded=yes
>d [15/Jun/2007:17:01:10 +0200] select_timeout: 9 seconds to send browse update

Actions: View

Attachments on bug 244001: 157121 | 157136