Attachment 222321 Details for Bug 308691 – cups debug2 error log, obtained by executing the above mentioned steps

cups debug2 error log, obtained by executing the above mentioned steps

error_log (text/plain), 149.81 KB, created by Mumrel on 2007-10-10 10:47:29 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Mumrel

Created: 2007-10-10 10:47:29 UTC

Size: 149.81 KB

patch

obsolete

>I [10/Oct/2007:12:33:48 +0200] Listening to 127.0.0.1:631 (IPv4)
>I [10/Oct/2007:12:33:48 +0200] Listening to /var/run/cups/cups.sock (Domain)
>d [10/Oct/2007:12:33:48 +0200] cupsdAddLocation: added location 'CUPS_INTERNAL_BROWSE_ACL'
>d [10/Oct/2007:12:33:48 +0200] cupsdAllowHost(loc=0x802554c8(CUPS_INTERNAL_BROWSE_ACL), name="@LOCAL")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddLocation: added location '/'
>d [10/Oct/2007:12:33:48 +0200] cupsdAllowHost(loc=0x802558f0(/), name="localhost")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddLocation: added location '/admin'
>d [10/Oct/2007:12:33:48 +0200] cupsdAllowHost(loc=0x80255978(/admin), name="localhost")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddLocation: added location '/admin/conf'
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255a00, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAllowHost(loc=0x80255a00(/admin/conf), name="localhost")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=(nil), op=6(Send-Document))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255ae8, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255ae8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=7(Send-URI))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255b58, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255b58, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=c(Hold-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255bc8, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255bc8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=d(Release-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255c50, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255c50, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=e(Restart-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255cb0, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255cb0, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=12(Purge-Jobs))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255d40, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255d40, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=14(Set-Job-Attributes))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255db0, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255db0, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=17(Create-Job-Subscription))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255e48, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255e48, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=1a(Renew-Subscription))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255eb8, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255eb8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=1b(Cancel-Subscription))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255f58, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255f58, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=1c(Get-Notifications))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255fc8, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80255fc8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=2c(Reprocess-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256038, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256038, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=2d(Cancel-Current-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802560a8, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802560a8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=2e(Suspend-Current-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256158, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256158, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=2f(Resume-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802561c8, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802561c8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x80255ae8, op=400d(CUPS-Move-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256118, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256118, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=(nil), op=10(Pause-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802562b0, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=11(Resume-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256360, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=13(Set-Printer-Attributes))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802563c0, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=22(Enable-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256310, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=23(Disable-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256488, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=24(Pause-Printer-After-Current-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256420, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=25(Hold-New-Jobs))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256558, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=26(Release-Held-New-Jobs))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802564e8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=27(Deactivate-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256620, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=28(Activate-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802565b8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=29(Restart-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273420, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=2a(Shutdown-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80256680, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=2b(Startup-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802734d8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=30(Promote-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273460, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=31(Schedule-Job-After))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273598, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=4003(CUPS-Add-Modify-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273518, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=4004(CUPS-Delete-Printer))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273670, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=4006(CUPS-Add-Modify-Class))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802735e8, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=4007(CUPS-Delete-Class))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273740, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=4008(CUPS-Accept-Jobs))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802736b0, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=4009(CUPS-Reject-Jobs))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273818, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802562b0, op=400a(CUPS-Set-Default))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273780, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=(nil), op=8(Cancel-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802737d0, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x802737d0, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=0x802737d0, op=400e(CUPS-Authenticate-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273858, name="@OWNER")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddName(loc=0x80273858, name="@SYSTEM")
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPolicyOp(p=0x80255ab8, po=(nil), op=0(unknown-0000))
>I [10/Oct/2007:12:33:48 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
>I [10/Oct/2007:12:33:48 +0200] Using default TempDir of /var/spool/cups/tmp...
>I [10/Oct/2007:12:33:48 +0200] Cleaning out old temporary files in "/var/spool/cups/tmp"...
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_CACHEDIR=/var/cache/cups
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_DATADIR=/usr/share/cups
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_DOCROOT=/usr/share/doc/cups-1.2.12
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_FONTPATH=/usr/share/cups/fonts
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_REQUESTROOT=/var/spool/cups
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_SERVERBIN=/usr/lib/cups
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_SERVERROOT=/etc/cups
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_STATEDIR=/var/run/cups
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: SERVER_ADMIN=root@localhost.localdomain
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: SOFTWARE=CUPS/1.2.12
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: TMPDIR=/var/spool/cups/tmp
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: USER=root
>I [10/Oct/2007:12:33:48 +0200] Configured for up to 100 clients.
>I [10/Oct/2007:12:33:48 +0200] Allowing up to 100 client connections per host.
>I [10/Oct/2007:12:33:48 +0200] Using policy "default" as the default!
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: NumPolicies=1
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: Policies[0]="default"
>I [10/Oct/2007:12:33:48 +0200] Full reload is required.
>I [10/Oct/2007:12:33:48 +0200] Loaded MIME database from '/etc/cups': 34 types, 38 filters...
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/octet-stream
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/pdf
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/postscript
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.cups-command
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.cups-form
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.cups-pdf
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.cups-postscript
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.cups-ppd
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.cups-raster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.cups-raw
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/vnd.hp-hpgl
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/x-cshell
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/x-csource
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/x-perl
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type application/x-shell
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/gif
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/jpeg
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/png
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/tiff
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-alias
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-bitmap
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-icon
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-photocd
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-portable-anymap
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-portable-bitmap
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-portable-graymap
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-portable-pixmap
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-sgi-rgb
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-sun-raster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-xbitmap
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type image/x-xpixmap
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type text/css
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type text/html
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: type text/plain
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/pdf to application/postscript 33 pdftops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/postscript to application/vnd.cups-postscript 66 pstops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/vnd.cups-postscript to application/vnd.cups-raster 100 pstoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/vnd.hp-hpgl to application/postscript 66 hpgltops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/x-cshell to application/postscript 33 texttops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/x-csource to application/postscript 33 texttops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/x-perl to application/postscript 33 texttops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter application/x-shell to application/postscript 33 texttops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/gif to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/gif to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/png to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/png to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-postscript 66 imagetops
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-raster 100 imagetoraster
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter text/html to application/postscript 33 texttopaps
>d [10/Oct/2007:12:33:48 +0200] cupsdReadConfiguration: filter text/plain to application/postscript 33 texttopaps
>D [10/Oct/2007:12:33:48 +0200] Loading printer samsung...
>d [10/Oct/2007:12:33:48 +0200] cupsdAddPrinter("samsung")
>d [10/Oct/2007:12:33:48 +0200] cupsdFindBest: uri = "/printers/samsung"...
>d [10/Oct/2007:12:33:48 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:33:48 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:33:48 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:33:48 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:33:48 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:33:48 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=2(Print-Job))
>d [10/Oct/2007:12:33:48 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:33:48 +0200] add_printer_filter: samsung: adding filter application/vnd.cups-raw printer/samsung 0 -
>d [10/Oct/2007:12:33:48 +0200] add_printer_filter: samsung: adding filter application/vnd.cups-postscript printer/samsung 0 foomatic-rip
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: 35 types, 40 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/octet-stream not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/pdf needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/postscript needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.cups-command not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.cups-form not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.cups-pdf not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.cups-postscript needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.cups-ppd not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.cups-raster not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.cups-raw needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/vnd.hp-hpgl needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/x-cshell needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/x-csource needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/x-perl needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: application/x-shell needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/gif needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/jpeg needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/png needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/tiff needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-alias not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-bitmap needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-icon not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-photocd needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-portable-anymap needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-portable-bitmap needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-portable-graymap needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-portable-pixmap needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-sgi-rgb needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-sun-raster needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-xbitmap needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: image/x-xpixmap needs 1 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: printer/samsung not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: text/css not supported
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: text/html needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: text/plain needs 2 filters
>d [10/Oct/2007:12:33:48 +0200] add_printer_formats: samsung: 26 supported types
>I [10/Oct/2007:12:33:48 +0200] Loading job cache file "/var/cache/cups/job.cache"...
>D [10/Oct/2007:12:33:48 +0200] Loading job 193 from cache...
>D [10/Oct/2007:12:33:48 +0200] Loading job 194 from cache...
>D [10/Oct/2007:12:33:48 +0200] Loading job 195 from cache...
>D [10/Oct/2007:12:33:48 +0200] Loading job 196 from cache...
>D [10/Oct/2007:12:33:48 +0200] Loading job 197 from cache...
>I [10/Oct/2007:12:33:48 +0200] Full reload complete.
>d [10/Oct/2007:12:33:48 +0200] cupsdStartListening: 2 Listeners
>I [10/Oct/2007:12:33:48 +0200] Listening to 127.0.0.1:631 on fd 3...
>I [10/Oct/2007:12:33:48 +0200] Listening to /var/run/cups/cups.sock on fd 4...
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_SERVER=/var/run/cups/cups.sock
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: CUPS_ENCRYPTION=IfRequested
>d [10/Oct/2007:12:33:48 +0200] cupsdSetEnv: IPP_PORT=631
>d [10/Oct/2007:12:33:48 +0200] cupsdResumeListening: Setting input bits...
>d [10/Oct/2007:12:33:48 +0200] cupsdResumeListening: Adding fd 3 to InputSet...
>d [10/Oct/2007:12:33:48 +0200] cupsdResumeListening: Adding fd 4 to InputSet...
>d [10/Oct/2007:12:33:48 +0200] cupsdStartBrowsing: Adding fd 5 to InputSet...
>d [10/Oct/2007:12:33:48 +0200] cupsdStartServer: Adding fd 6 to InputSet...
>d [10/Oct/2007:12:33:48 +0200] cupsdAddCert: adding certificate for pid 0
>d [10/Oct/2007:12:33:48 +0200] cupsdAddCert: NumSystemGroups=2
>d [10/Oct/2007:12:33:48 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:33:49 +0200] stringpool: 296 strings, 6856 allocated, 6096 total bytes
>d [10/Oct/2007:12:33:49 +0200] select_timeout: 31 seconds to send browse update
>d [10/Oct/2007:12:33:59 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 0
>D [10/Oct/2007:12:33:59 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:33:59 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:33:59 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:33:59 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:33:59 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:33:59 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:33:59 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:33:59 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:33:59 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:34:00 +0200] select_timeout: 20 seconds to send browse update
>d [10/Oct/2007:12:34:20 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:34:20 +0200] select_timeout: 31 seconds to send browse update
>d [10/Oct/2007:12:34:29 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 0
>D [10/Oct/2007:12:34:29 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:34:29 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:34:29 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:34:29 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:34:29 +0200] POST /
>d [10/Oct/2007:12:34:29 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=327, con->file=-1
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8, used=319, file=-1 state=6
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=319, con->file=-1
>d [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 4002
>D [10/Oct/2007:12:34:29 +0200] CUPS-Get-Printers
>d [10/Oct/2007:12:34:29 +0200] get_printers(0x802997b0[8], 0)
>d [10/Oct/2007:12:34:29 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=4002(CUPS-Get-Printers))
>d [10/Oct/2007:12:34:29 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:34:29 +0200] add_printer_state_reasons(0x802997b0[8], 0x8026d9b0[samsung])
>d [10/Oct/2007:12:34:29 +0200] printer-uri-supported="ipp://localhost:631/printers/samsung"
>d [10/Oct/2007:12:34:29 +0200] copy_attrs(to=0x8029cf80, from=0x8027d680, ra=0x8029d178, group=0, quickcopy=0)
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029cf80, 0x8027d790[printer-name,4,42])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029cf80, 0x8027d7e0[printer-location,4,41])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029cf80, 0x8027d850[printer-info,4,41])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029cf80, 0x8027da50[device-uri,4,45])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029cf80, 0x8027e998[printer-make-and-model,4,41])
>d [10/Oct/2007:12:34:29 +0200] copy_attrs(to=0x8029cf80, from=0x8026e348, ra=0x8029d178, group=0, quickcopy=-2147483648)
>D [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:34:29 +0200] select_timeout: 22 seconds to send browse update
>d [10/Oct/2007:12:34:29 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:34:29 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:34:29 +0200] POST /
>d [10/Oct/2007:12:34:29 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=129, con->file=-1
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8, used=121, file=-1 state=6
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=121, con->file=-1
>d [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 4005
>D [10/Oct/2007:12:34:29 +0200] CUPS-Get-Classes
>d [10/Oct/2007:12:34:29 +0200] get_printers(0x802997b0[8], 1)
>d [10/Oct/2007:12:34:29 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=4005(CUPS-Get-Classes))
>d [10/Oct/2007:12:34:29 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>D [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:34:29 +0200] select_timeout: 22 seconds to send browse update
>d [10/Oct/2007:12:34:29 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:34:29 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:34:29 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:34:29 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:34:29 +0200] POST /
>d [10/Oct/2007:12:34:29 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=123, con->file=-1
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8, used=115, file=-1 state=6
>d [10/Oct/2007:12:34:29 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=115, con->file=-1
>d [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000b
>D [10/Oct/2007:12:34:29 +0200] Get-Printer-Attributes ipp://localhost/printers/samsung
>d [10/Oct/2007:12:34:29 +0200] get_printer_attrs(0x802997b0[8], ipp://localhost/printers/samsung)
>d [10/Oct/2007:12:34:29 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=b(Get-Printer-Attributes))
>d [10/Oct/2007:12:34:29 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:34:29 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:34:29 +0200] add_printer_state_reasons(0x802997b0[8], 0x8026d9b0[samsung])
>d [10/Oct/2007:12:34:29 +0200] printer-uri-supported="ipp://localhost:631/printers/samsung"
>d [10/Oct/2007:12:34:29 +0200] add_queued_job_count(0x802997b0[8], 0x8026d9b0[samsung])
>d [10/Oct/2007:12:34:29 +0200] copy_attrs(to=0x8029d428, from=0x8027d680, ra=(nil), group=0, quickcopy=0)
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d6a8[uri-authentication-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d738[uri-security-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d790[printer-name,4,42])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d7e0[printer-location,4,41])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d850[printer-info,4,41])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d8a0[printer-more-info,4,45])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d8f0[job-quota-period,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d940[job-k-limit,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d988[job-page-limit,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d9d8[job-sheets-default,4,42])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027da50[device-uri,4,45])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027e8f0[color-supported,4,22])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027e930[pages-per-minute,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027e998[printer-make-and-model,4,41])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027e9d0[media-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027ede0[media-default,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027f050[port-monitor,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027f0a0[port-monitor-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80292db0[finishings-supported,4,23])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80292540[finishings-default,4,23])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80280ee0[document-format-supported,4,49])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80292518[copies-default,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x802929e0[job-hold-until-default,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x802929a0[job-priority-default,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80292390[number-up-default,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x802924d8[orientation-requested-default,4,23])
>d [10/Oct/2007:12:34:29 +0200] copy_attrs(to=0x8029d428, from=0x8026e348, ra=(nil), group=0, quickcopy=-2147483648)
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d628[charset-configured,4,47])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027e890[charset-supported,4,47])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026ea68[compression-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d5c0[copies-supported,4,33])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d4f0[document-format-default,4,49])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d498[generated-natural-language-supported,4,48])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026eae8[ipp-versions-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026e670[job-hold-until-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d470[job-priority-supported,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026e878[job-sheets-supported,4,42])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026e5d0[multiple-document-handling-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d188[multiple-document-jobs-supported,4,22])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027ca00[multiple-operation-time-out,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027c9a0[natural-language-configured,4,48])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d570[notify-attributes-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027c940[notify-lease-duration-default,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027c8e8[notify-lease-duration-supported,4,33])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027c788[notify-max-events-supported,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026eb50[notify-events-default,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027cad8[notify-events-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026eb88[notify-pull-method-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80291ec0[notify-schemes-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d1d0[number-up-supported,4,21])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8027d258[operations-supported,4,23])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80292eb8[orientation-requested-supported,4,23])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x80280d00[page-ranges-supported,4,22])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026e390[pdl-override-supported,4,44])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026e468[printer-error-policy-supported,4,42])
>d [10/Oct/2007:12:34:29 +0200] copy_attribute(0x8029d428, 0x8026e558[printer-op-policy-supported,4,42])
>D [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:34:29 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:34:29 +0200] select_timeout: 22 seconds to send browse update
>d [10/Oct/2007:12:34:29 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:34:29 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:34:30 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:34:30 +0200] select_timeout: 21 seconds to send browse update
>d [10/Oct/2007:12:34:49 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:34:49 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:34:49 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:34:49 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:34:49 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:34:49 +0200] stringpool: 317 strings, 7264 allocated, 6592 total bytes
>d [10/Oct/2007:12:34:50 +0200] select_timeout: 1 seconds to send browse update
>d [10/Oct/2007:12:34:51 +0200] select_timeout: 31 seconds to send browse update
>d [10/Oct/2007:12:35:22 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:35:22 +0200] select_timeout: 31 seconds to send browse update
>d [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 0
>D [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:35:36 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:36 +0200] POST /
>d [10/Oct/2007:12:35:36 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=75, con->file=-1
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8, used=67, file=-1 state=6
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=67, con->file=-1
>d [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 4001
>D [10/Oct/2007:12:35:36 +0200] CUPS-Get-Default
>d [10/Oct/2007:12:35:36 +0200] get_default(0x802997b0[8])
>d [10/Oct/2007:12:35:36 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=4001(CUPS-Get-Default))
>d [10/Oct/2007:12:35:36 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>D [10/Oct/2007:12:35:36 +0200] CUPS-Get-Default client-error-not-found: Kein Standarddrucker
>D [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest: 8 status_code=406 (client-error-not-found)
>d [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:35:36 +0200] select_timeout: 17 seconds to send browse update
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:36 +0200] POST /
>d [10/Oct/2007:12:35:36 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=75, con->file=-1
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8, used=67, file=-1 state=6
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=67, con->file=-1
>d [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 4002
>D [10/Oct/2007:12:35:36 +0200] CUPS-Get-Printers
>d [10/Oct/2007:12:35:36 +0200] get_printers(0x802997b0[8], 0)
>d [10/Oct/2007:12:35:36 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=4002(CUPS-Get-Printers))
>d [10/Oct/2007:12:35:36 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:36 +0200] add_printer_state_reasons(0x802997b0[8], 0x8026d9b0[samsung])
>d [10/Oct/2007:12:35:36 +0200] printer-uri-supported="ipp://localhost:631/printers/samsung"
>d [10/Oct/2007:12:35:36 +0200] add_queued_job_count(0x802997b0[8], 0x8026d9b0[samsung])
>d [10/Oct/2007:12:35:36 +0200] copy_attrs(to=0x8029f4a8, from=0x8027d680, ra=(nil), group=0, quickcopy=0)
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d6a8[uri-authentication-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d738[uri-security-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d790[printer-name,4,42])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d7e0[printer-location,4,41])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d850[printer-info,4,41])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d8a0[printer-more-info,4,45])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d8f0[job-quota-period,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d940[job-k-limit,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d988[job-page-limit,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d9d8[job-sheets-default,4,42])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027da50[device-uri,4,45])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027e8f0[color-supported,4,22])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027e930[pages-per-minute,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027e998[printer-make-and-model,4,41])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027e9d0[media-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027ede0[media-default,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027f050[port-monitor,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027f0a0[port-monitor-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80292db0[finishings-supported,4,23])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80292540[finishings-default,4,23])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80280ee0[document-format-supported,4,49])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80292518[copies-default,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x802929e0[job-hold-until-default,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x802929a0[job-priority-default,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80292390[number-up-default,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x802924d8[orientation-requested-default,4,23])
>d [10/Oct/2007:12:35:36 +0200] copy_attrs(to=0x8029f4a8, from=0x8026e348, ra=(nil), group=0, quickcopy=-2147483648)
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d628[charset-configured,4,47])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027e890[charset-supported,4,47])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026ea68[compression-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d5c0[copies-supported,4,33])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d4f0[document-format-default,4,49])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d498[generated-natural-language-supported,4,48])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026eae8[ipp-versions-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026e670[job-hold-until-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d470[job-priority-supported,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026e878[job-sheets-supported,4,42])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026e5d0[multiple-document-handling-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d188[multiple-document-jobs-supported,4,22])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027ca00[multiple-operation-time-out,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027c9a0[natural-language-configured,4,48])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d570[notify-attributes-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027c940[notify-lease-duration-default,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027c8e8[notify-lease-duration-supported,4,33])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027c788[notify-max-events-supported,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026eb50[notify-events-default,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027cad8[notify-events-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026eb88[notify-pull-method-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80291ec0[notify-schemes-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d1d0[number-up-supported,4,21])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8027d258[operations-supported,4,23])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80292eb8[orientation-requested-supported,4,23])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x80280d00[page-ranges-supported,4,22])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026e390[pdl-override-supported,4,44])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026e468[printer-error-policy-supported,4,42])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x8029f4a8, 0x8026e558[printer-op-policy-supported,4,42])
>D [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:35:36 +0200] select_timeout: 17 seconds to send browse update
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:36 +0200] POST /
>d [10/Oct/2007:12:35:36 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=374, con->file=-1
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8, used=366, file=-1 state=6
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=366, con->file=-1
>d [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000b
>D [10/Oct/2007:12:35:36 +0200] Get-Printer-Attributes ipp://localhost/printers/samsung
>d [10/Oct/2007:12:35:36 +0200] get_printer_attrs(0x802997b0[8], ipp://localhost/printers/samsung)
>d [10/Oct/2007:12:35:36 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=b(Get-Printer-Attributes))
>d [10/Oct/2007:12:35:36 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:36 +0200] add_printer_state_reasons(0x802997b0[8], 0x8026d9b0[samsung])
>d [10/Oct/2007:12:35:36 +0200] printer-uri-supported="ipp://localhost:631/printers/samsung"
>d [10/Oct/2007:12:35:36 +0200] add_queued_job_count(0x802997b0[8], 0x8026d9b0[samsung])
>d [10/Oct/2007:12:35:36 +0200] copy_attrs(to=0x802a0000, from=0x8027d680, ra=0x8029fde8, group=0, quickcopy=0)
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x802a0000, 0x8027d790[printer-name,4,42])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x802a0000, 0x8027d7e0[printer-location,4,41])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x802a0000, 0x8027d850[printer-info,4,41])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x802a0000, 0x8027da50[device-uri,4,45])
>d [10/Oct/2007:12:35:36 +0200] copy_attribute(0x802a0000, 0x8027e998[printer-make-and-model,4,41])
>d [10/Oct/2007:12:35:36 +0200] copy_attrs(to=0x802a0000, from=0x8026e348, ra=0x8029fde8, group=0, quickcopy=-2147483648)
>D [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:35:36 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:35:36 +0200] select_timeout: 17 seconds to send browse update
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient(lis=0x80255608) 3 Clients = 1
>D [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: 10 from localhost:631 (IPv4)
>d [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: 10 connected to server on localhost6.localdomain6:631
>d [10/Oct/2007:12:35:36 +0200] cupsdAcceptClient: Adding fd 10 to InputSet...
>d [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 10, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:36 +0200] cupsdReadClient: 10 GET /printers/samsung.ppd HTTP/1.1
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: uri = "/printers/samsung"...
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:36 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: con->uri="/printers/samsung.ppd", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:36 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: con->uri="/printers/samsung.ppd", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:36 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:36 +0200] get_file: 10 filename=/etc/cups/ppd/samsung.ppd size=28013
>d [10/Oct/2007:12:35:36 +0200] is_cgi(con=0x802a07f0, filename="/etc/cups/ppd/samsung.ppd", filestats=0xbfdb2134, type=application/vnd.cups-ppd)
>d [10/Oct/2007:12:35:36 +0200] is_cgi: Returning 0...
>D [10/Oct/2007:12:35:36 +0200] write_file: 10 file=11
>d [10/Oct/2007:12:35:36 +0200] write_file: Adding fd 10 to OutputSet...
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: Read 16384 bytes from file 11...
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: Read 11629 bytes from file 11...
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: 10 bytes < 0
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: Removing fd 10 from OutputSet...
>d [10/Oct/2007:12:35:36 +0200] cupsdWriteClient: 10 Closing data file 11.
>d [10/Oct/2007:12:35:37 +0200] select_timeout: 16 seconds to send browse update
>d [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 2
>D [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: 11 from localhost (Domain)
>d [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: 11 connected to server on localhost:631
>d [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: Adding fd 11 to InputSet...
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 POST /printers/samsung HTTP/1.1
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: uri = "/printers/samsung"...
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:38 +0200] cupsdAuthorize: con->uri="/printers/samsung", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:38 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:38 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: con->uri="/printers/samsung", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:38 +0200] POST /printers/samsung
>d [10/Oct/2007:12:35:38 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=25683, con->file=-1
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11, used=227, file=-1 state=6
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=25675, con->file=-1
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 REQUEST /var/spool/cups/00000000=12
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 writing 16128 bytes to 12
>d [10/Oct/2007:12:35:38 +0200] select_timeout: 15 seconds to send browse update
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11, used=0, file=12 state=6
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=9320, con->file=12
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 writing 9320 bytes to 12
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 11 Closing data file 12, size=25448.
>d [10/Oct/2007:12:35:38 +0200] cupsdProcessIPPRequest(0x802a3e60[11]): operation_id = 0002
>D [10/Oct/2007:12:35:38 +0200] Print-Job ipp://localhost/printers/samsung
>d [10/Oct/2007:12:35:38 +0200] print_job(0x802a3e60[11], ipp://localhost/printers/samsung)
>D [10/Oct/2007:12:35:38 +0200] print_job: auto-typing file...
>D [10/Oct/2007:12:35:38 +0200] print_job: request file type is application/postscript.
>d [10/Oct/2007:12:35:38 +0200] add_job(0x802a3e60[11], ipp://localhost/printers/samsung)
>d [10/Oct/2007:12:35:38 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=2(Print-Job))
>d [10/Oct/2007:12:35:38 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: con->uri="/printers/samsung", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:38 +0200] check_quotas(0x802a3e60[11], 0x8026d9b0[samsung])
>D [10/Oct/2007:12:35:38 +0200] add_job: setting context of job to UNKNOWN SL
>D [10/Oct/2007:12:35:38 +0200] add_job: requesting-user-name="jan"
>D [10/Oct/2007:12:35:38 +0200] Adding default job-sheets values "none,none"...
>I [10/Oct/2007:12:35:38 +0200] Adding start banner page "none" to job 198.
>d [10/Oct/2007:12:35:38 +0200] copy_banner(0x802a3e60[11], 0x8029d370[198], none)
>d [10/Oct/2007:12:35:38 +0200] add_job_state_reasons(0x802a3e60[11], 198)
>d [10/Oct/2007:12:35:38 +0200] cupsdAddEvent(event=job-created, dest=0x8026d9b0(samsung), job=0x8029d370(198), text="Job created.", ...)
>D [10/Oct/2007:12:35:38 +0200] Discarding unused job-created event...
>d [10/Oct/2007:12:35:38 +0200] add_file(con=0x802a3e60[11], job=198, filetype=application/postscript, compression=0)
>I [10/Oct/2007:12:35:38 +0200] Adding end banner page "none" to job 198.
>d [10/Oct/2007:12:35:38 +0200] copy_banner(0x802a3e60[11], 0x8029d370[198], none)
>I [10/Oct/2007:12:35:38 +0200] Job 198 queued on "samsung" by "jan".
>D [10/Oct/2007:12:35:38 +0200] Job 198 hold_until = 0
>d [10/Oct/2007:12:35:38 +0200] cupsdSaveJob(job=0x8029d370(198)): job->attrs=0x8029cea8
>d [10/Oct/2007:12:35:38 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:35:38 +0200] cupsdCheckJobs: Job 198: state_value=3, loaded=yes
>d [10/Oct/2007:12:35:38 +0200] start_job: id = 198, file = 0/1
>d [10/Oct/2007:12:35:38 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="%s "%s" state changed.", ...)
>D [10/Oct/2007:12:35:38 +0200] Discarding unused printer-state-changed event...
>D [10/Oct/2007:12:35:38 +0200] job-sheets=none,none
>D [10/Oct/2007:12:35:38 +0200] banner_page = 0
>D [10/Oct/2007:12:35:38 +0200] [Job 198] argv[0]="samsung"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] argv[1]="198"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] argv[2]="jan"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] argv[3]=""
>D [10/Oct/2007:12:35:38 +0200] [Job 198] argv[4]="1"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] argv[5]="job-uuid=urn:uuid:20329fc3-e354-3749-5702-547f69335305"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] argv[6]="/var/spool/cups/d00198-001"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[1]="CUPS_DATADIR=/usr/share/cups"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups-1.2.12"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[6]="CUPS_SERVERROOT=/etc/cups"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[7]="CUPS_STATEDIR=/var/run/cups"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[9]="SERVER_ADMIN=root@localhost.localdomain"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[10]="SOFTWARE=CUPS/1.2.12"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[11]="TMPDIR=/var/spool/cups/tmp"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[12]="USER=root"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[14]="CUPS_ENCRYPTION=IfRequested"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[15]="IPP_PORT=631"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[16]="CHARSET=utf-8"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[17]="LANG=de_DE"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[18]="PPD=/etc/cups/ppd/samsung.ppd"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[19]="RIP_MAX_CACHE=8m"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[20]="CONTENT_TYPE=application/postscript"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[21]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4e8_3242_2R21BADWA02453D0_if0_printer_noserial"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[22]="PRINTER=samsung"
>D [10/Oct/2007:12:35:38 +0200] [Job 198] envp[23]="FINAL_CONTENT_TYPE=printer/samsung"
>d [10/Oct/2007:12:35:38 +0200] start_job: status_pipes = [ 15 16 ]
>d [10/Oct/2007:12:35:38 +0200] start_job: filter="/usr/lib/cups/filter/pstops"
>d [10/Oct/2007:12:35:38 +0200] start_job: filterfds[0]=[ 17 18 ]
>d [10/Oct/2007:12:35:38 +0200] cupsdStartProcess("/usr/lib/cups/filter/pstops", 0x802a8e20, 0xbfdad9d8, -1, 18, 16)
>d [10/Oct/2007:12:35:38 +0200] start_job: Closing filter pipes for slot 1 [ -1 -1 ]...
>I [10/Oct/2007:12:35:38 +0200] Started filter /usr/lib/cups/filter/pstops (PID 3331) for job 198.
>d [10/Oct/2007:12:35:38 +0200] start_job: print_pipes = [ 19 20 ]
>d [10/Oct/2007:12:35:38 +0200] start_job: filter="/usr/lib/cups/filter/foomatic-rip"
>d [10/Oct/2007:12:35:38 +0200] start_job: filterfds[1]=[ 19 20 ]
>d [10/Oct/2007:12:35:38 +0200] cupsdStartProcess("/usr/lib/cups/filter/foomatic-rip", 0x802a8e20, 0xbfdad9d8, 17, 20, 16)
>d [10/Oct/2007:12:35:38 +0200] start_job: Closing filter pipes for slot 0 [ 17 18 ]...
>I [10/Oct/2007:12:35:38 +0200] Started filter /usr/lib/cups/filter/foomatic-rip (PID 3332) for job 198.
>d [10/Oct/2007:12:35:38 +0200] start_job: backend="/usr/lib/cups/backend/hal"
>d [10/Oct/2007:12:35:38 +0200] start_job: filterfds[0] = [ -1 -1 ]
>d [10/Oct/2007:12:35:38 +0200] cupsdStartProcess("/usr/lib/cups/backend/hal", 0x802a8e20, 0xbfdad9d8, 19, -1, 16)
>I [10/Oct/2007:12:35:38 +0200] Started backend /usr/lib/cups/backend/hal (PID 3333) for job 198.
>d [10/Oct/2007:12:35:38 +0200] start_job: Closing print pipes [ 19 20 ]...
>d [10/Oct/2007:12:35:38 +0200] start_job: Closing back pipes [ 13 14 ]...
>d [10/Oct/2007:12:35:38 +0200] start_job: Closing status output pipe 16...
>d [10/Oct/2007:12:35:38 +0200] start_job: Closing filter pipes for slot 0 [ -1 -1 ]...
>d [10/Oct/2007:12:35:38 +0200] start_job: Adding fd 15 to InputSet...
>d [10/Oct/2007:12:35:38 +0200] cupsdAddEvent(event=job-state, dest=0x8026d9b0(samsung), job=0x8029d370(198), text="Job #%d started.", ...)
>D [10/Oct/2007:12:35:38 +0200] Discarding unused job-state event...
>D [10/Oct/2007:12:35:38 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
>d [10/Oct/2007:12:35:38 +0200] cupsdProcessIPPRequest: Adding fd 11 to OutputSet...
>d [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 3
>D [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: 13 from localhost (Domain)
>d [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: 13 connected to server on localhost:631
>d [10/Oct/2007:12:35:38 +0200] cupsdAcceptClient: Adding fd 13 to InputSet...
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 13 POST / HTTP/1.1
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:38 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:38 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:38 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:38 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:38 +0200] POST /
>d [10/Oct/2007:12:35:38 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 13 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:35:38 +0200] cupsdProcessIPPRequest(0x802a93e0[13]): operation_id = 000a
>D [10/Oct/2007:12:35:38 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:35:38 +0200] get_jobs(0x802a93e0[13], ipp://localhost/jobs/)
>d [10/Oct/2007:12:35:38 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:35:38 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:38 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:35:38 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:35:38 +0200] add_job_state_reasons(0x802a93e0[13], 198)
>d [10/Oct/2007:12:35:38 +0200] copy_attrs(to=0x802acb30, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:35:38 +0200] copy_attribute(0x802acb30, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:35:38 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
>d [10/Oct/2007:12:35:38 +0200] cupsdProcessIPPRequest: Adding fd 13 to OutputSet...
>d [10/Oct/2007:12:35:38 +0200] cupsdWriteClient: 11 bytes < 0
>d [10/Oct/2007:12:35:38 +0200] cupsdWriteClient: Removing fd 11 from OutputSet...
>d [10/Oct/2007:12:35:38 +0200] cupsdWriteClient: 13 bytes < 0
>d [10/Oct/2007:12:35:38 +0200] cupsdWriteClient: Removing fd 13 from OutputSet...
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>d [10/Oct/2007:12:35:38 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:35:38 +0200] cupsdCloseClient: 13
>d [10/Oct/2007:12:35:38 +0200] cupsdCloseClient: Removing fd 13 from InputSet and OutputSet...
>I [10/Oct/2007:12:35:38 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:35:38 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:35:38 +0200] Discarding unused printer-state-changed event...
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Page = 595x842; 36,36 to 559,806
>D [10/Oct/2007:12:35:39 +0200] [Job 198] slow_collate=0, slow_duplex=0, slow_order=0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Before copy_comments - %!PS-Adobe-3.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %!PS-Adobe-3.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%Creator: Gnome Print Version 2.18.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%CreationDate: D:20071010123538
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%LanguageLevel: 2
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%DocumentMedia: Regular 595 842 0 () ()
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%Orientation: Portrait
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%BoundingBox: 0 0 595 842
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%Pages: 1
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%PageOrder: Ascend
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%Title:
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%DocumentSuppliedResources: procset pnome-print-procs-2.18.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%+ font (GnomeUni-DejaVuLGCSansMono) cvn
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%+ font (GnomeUni-DejaVuLGCSans) cvn
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%Requirements: numcopies(1)
>D [10/Oct/2007:12:35:39 +0200] [Job 198] %%EndComments
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Before copy_prolog - %%BeginDefaults
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Before copy_setup - %%BeginSetup
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Before page loop - %%Page: 1 1
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Copying page 1...
>D [10/Oct/2007:12:35:39 +0200] [Job 198] pagew = 523.0, pagel = 770.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] bboxw = 595, bboxl = 842
>D [10/Oct/2007:12:35:39 +0200] [Job 198] PageLeft = 36.0, PageRight = 559.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] PageTop = 806.0, PageBottom = 36.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] PageWidth = 595.0, PageLength = 842.0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Wrote 1 pages...
>d [10/Oct/2007:12:35:39 +0200] process_children()
>D [10/Oct/2007:12:35:39 +0200] PID 3331 (/usr/lib/cups/filter/pstops) exited with no errors.
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 3
>D [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: 13 from localhost (Domain)
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: 13 connected to server on localhost:631
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: Adding fd 13 to InputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 POST / HTTP/1.1
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:39 +0200] POST /
>d [10/Oct/2007:12:35:39 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=169, file=-1 state=6
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=169, con->file=-1
>d [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest(0x802a93e0[13]): operation_id = 000a
>D [10/Oct/2007:12:35:39 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:35:39 +0200] get_jobs(0x802a93e0[13], ipp://localhost/jobs/)
>d [10/Oct/2007:12:35:39 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:35:39 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:39 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:35:39 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:35:39 +0200] add_job_state_reasons(0x802a93e0[13], 198)
>d [10/Oct/2007:12:35:39 +0200] copy_attrs(to=0x802aca50, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
>d [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest: Adding fd 13 to OutputSet...
>d [10/Oct/2007:12:35:39 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:35:39 +0200] cupsdWriteClient: 13 bytes < 0
>d [10/Oct/2007:12:35:39 +0200] cupsdWriteClient: Removing fd 13 from OutputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:35:39 +0200] cupsdCloseClient: 13
>d [10/Oct/2007:12:35:39 +0200] cupsdCloseClient: Removing fd 13 from InputSet and OutputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 3
>D [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: 13 from localhost (Domain)
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: 13 connected to server on localhost:631
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: Adding fd 13 to InputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 POST / HTTP/1.1
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:39 +0200] POST /
>d [10/Oct/2007:12:35:39 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=169, file=-1 state=6
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=169, con->file=-1
>d [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest(0x802a93e0[13]): operation_id = 000a
>D [10/Oct/2007:12:35:39 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:35:39 +0200] get_jobs(0x802a93e0[13], ipp://localhost/jobs/)
>d [10/Oct/2007:12:35:39 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:35:39 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:39 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:35:39 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:35:39 +0200] add_job_state_reasons(0x802a93e0[13], 198)
>d [10/Oct/2007:12:35:39 +0200] copy_attrs(to=0x802aca50, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
>d [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest: Adding fd 13 to OutputSet...
>d [10/Oct/2007:12:35:39 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:35:39 +0200] cupsdWriteClient: 13 bytes < 0
>d [10/Oct/2007:12:35:39 +0200] cupsdWriteClient: Removing fd 13 from OutputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:35:39 +0200] cupsdCloseClient: 13
>d [10/Oct/2007:12:35:39 +0200] cupsdCloseClient: Removing fd 13 from InputSet and OutputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 3
>D [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: 13 from localhost (Domain)
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: 13 connected to server on localhost:631
>d [10/Oct/2007:12:35:39 +0200] cupsdAcceptClient: Adding fd 13 to InputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>D [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 POST / HTTP/1.1
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:35:39 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:35:39 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:39 +0200] POST /
>d [10/Oct/2007:12:35:39 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=169, file=-1 state=6
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=169, con->file=-1
>d [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest(0x802a93e0[13]): operation_id = 000a
>D [10/Oct/2007:12:35:39 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:35:39 +0200] get_jobs(0x802a93e0[13], ipp://localhost/jobs/)
>d [10/Oct/2007:12:35:39 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:35:39 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:35:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:35:39 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:35:39 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:35:39 +0200] add_job_state_reasons(0x802a93e0[13], 198)
>d [10/Oct/2007:12:35:39 +0200] copy_attrs(to=0x802aca50, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:35:39 +0200] copy_attribute(0x802aca50, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
>d [10/Oct/2007:12:35:39 +0200] cupsdProcessIPPRequest: Adding fd 13 to OutputSet...
>d [10/Oct/2007:12:35:39 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:35:39 +0200] cupsdWriteClient: 13 bytes < 0
>d [10/Oct/2007:12:35:39 +0200] cupsdWriteClient: Removing fd 13 from OutputSet...
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: 13, used=0, file=-1 state=0
>d [10/Oct/2007:12:35:39 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:35:39 +0200] cupsdCloseClient: 13
>d [10/Oct/2007:12:35:39 +0200] cupsdCloseClient: Removing fd 13 from InputSet and OutputSet...
>D [10/Oct/2007:12:35:39 +0200] [Job 198] foomatic-rip version $Revision$ running...
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Parsing PPD file ...
>D [10/Oct/2007:12:35:39 +0200] [Job 198] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option ColorSpace
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option Manualfeed
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option Resolution
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option Economode
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option MediaType
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option RET
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option Copies
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PageSize
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PageRegion
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option ImageableArea
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PaperDimension
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option HalftoningAlgorithm
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option Density
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option JamRecovery
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option AllowReprint
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option Altitude
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PageTimeout
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PowerSaving
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PowerSaveTime
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PageSizeJCL
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option PageSizePS
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Added option Font
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Parameter Summary
>D [10/Oct/2007:12:35:39 +0200] [Job 198] -----------------
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Spooler: cups
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Printer: samsung
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Shell: /bin/sh
>D [10/Oct/2007:12:35:39 +0200] [Job 198] PPD file: /etc/cups/ppd/samsung.ppd
>D [10/Oct/2007:12:35:39 +0200] [Job 198] ATTR file: 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Printer model: Samsung ML-1510 Foomatic/gdi
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Job title: 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] File(s) to be printed: 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] <STDIN>
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Pondering option 'job-uuid=urn:uuid:20329fc3-e354-3749-5702-547f69335305'
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Unknown option job-uuid=urn:uuid:20329fc3-e354-3749-5702-547f69335305.
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] ================================================
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] File: <STDIN>
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] ================================================
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Reading PostScript input ...
>D [10/Oct/2007:12:35:39 +0200] [Job 198] --> This document is DSC-conforming!
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another "%%Begin..." comment (is this a TeX/LaTeX/dvips-generated PostScript file?). Assuming start of "Prolog" here.
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] -----------
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginProlog
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%EndProlog
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] -----------
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginSetup
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *PageSize A4
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PageSize=A4 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: PageSize=A4
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PageSize=A4 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *Manualfeed Off
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Manualfeed=Off --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: Manualfeed=Off
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Manualfeed=Off --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *Resolution 600x600dpi
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Resolution=600x600dpi --> Option will be set by PostScript interpreter
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *Economode Off
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Economode=Off --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: Economode=Off
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Economode=Off --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *MediaType Normal
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: MediaType=Normal --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: MediaType=Normal
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: MediaType=Normal --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *Density 3
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Density=3 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: Density=3
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Density=3 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *JamRecovery Yes
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: JamRecovery=Yes --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: JamRecovery=Yes
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: JamRecovery=Yes --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *AllowReprint Yes
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: AllowReprint=Yes --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: AllowReprint=Yes
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: AllowReprint=Yes --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *Altitude Low
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Altitude=Low --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: Altitude=Low
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Altitude=Low --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *Copies 1
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Copies=1 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: Copies=1
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: Copies=1 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *PageTimeout 15
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PageTimeout=15 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: PageTimeout=15
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PageTimeout=15 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *PowerSaving On
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PowerSaving=On --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: PowerSaving=On
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PowerSaving=On --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *PowerSaveTime 5
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PowerSaveTime=5 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %% FoomaticRIPOptionSetting: PowerSaveTime=5
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: PowerSaveTime=5 --> Setting option
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%EndSetup
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Inserting PostScript code for CUPS' page accounting
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] -----------
>D [10/Oct/2007:12:35:39 +0200] [Job 198] New page:  1 1
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Inserting option code into "PageSetup" section.
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginPageSetup
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%BeginFeature: *HalftoningAlgorithm Accurate
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Option: HalftoningAlgorithm=Accurate --> Option will be set by PostScript interpreter
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Found: %%EndPageSetup
>D [10/Oct/2007:12:35:39 +0200] [Job 198] End of page header
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Flushing FIFO.
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Starting renderer
>D [10/Oct/2007:12:35:39 +0200] [Job 198] JCL: <job data> 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] renderer PID kid4=3338
>D [10/Oct/2007:12:35:39 +0200] [Job 198] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=gdi -sOutputFile=- - | perl -p -e 's/PJL PAGE LETTER/PJL PAGE A4/; s/PJL PAGE (\S*) AUTO/PJL PAGE $1 AUTO/; s/PJL SET TONERSAVE = OFF/PJL SET TONERSAVE = OFF\r\n\@PJL SET ECONOMODE = OFF/; s/PJL SET PAPERTYPE = NORMAL/PJL SET PAPERTYPE = NORMAL/; s/PJL SET DENSITY = 1/PJL SET DENSITY = 3/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET RET = OFF\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET JAMRECOVERY = ON\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET REPRINT = ON\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET ALTITUDE = OFF\r\n$1/; s/PJL COPIES = 1/PJL COPIES = 1/; s/(\@PJL ENTER LANGUAGE)/\@PJL DEFAULT TIMEOUT = 15\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL DEFAULT POWERSAVE = ON\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL DEFAULT POWERSAVETIME = 5\r\n$1/; '
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Closing renderer
>D [10/Oct/2007:12:35:39 +0200] [Job 198] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=gdi' '-sOutputFile=%stdout' '-'
>d [10/Oct/2007:12:35:39 +0200] [Job 198] PAGE: 1 1
>d [10/Oct/2007:12:35:39 +0200] cupsdAddEvent(event=job-progress, dest=0x8026d9b0(samsung), job=0x8029d370(198), text="Printed %d page(s).", ...)
>D [10/Oct/2007:12:35:39 +0200] Discarding unused job-progress event...
>D [10/Oct/2007:12:35:39 +0200] [Job 198] tail process done writing data to STDOUT
>D [10/Oct/2007:12:35:39 +0200] [Job 198] KID4 finished
>D [10/Oct/2007:12:35:39 +0200] [Job 198] KID3 finished
>D [10/Oct/2007:12:35:39 +0200] [Job 198] KID4 exited with status 0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] KID3 exited with status 0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Renderer exit stat: 0
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Renderer process finished
>D [10/Oct/2007:12:35:39 +0200] [Job 198] 
>D [10/Oct/2007:12:35:39 +0200] [Job 198] Closing foomatic-rip.
>d [10/Oct/2007:12:35:39 +0200] process_children()
>D [10/Oct/2007:12:35:39 +0200] PID 3332 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
>d [10/Oct/2007:12:35:40 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:35:51 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:35:51 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:35:51 +0200] stringpool: 395 strings, 8376 allocated, 8272 total bytes
>d [10/Oct/2007:12:35:51 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:36:02 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:36:02 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:36:02 +0200] select_timeout: 8 seconds to send browse update
>I [10/Oct/2007:12:36:08 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:36:08 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:36:08 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:36:08 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 3
>D [10/Oct/2007:12:36:08 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:36:08 +0200] cupsdAcceptClient: 14 from localhost (Domain)
>d [10/Oct/2007:12:36:08 +0200] cupsdAcceptClient: 14 connected to server on localhost:631
>d [10/Oct/2007:12:36:08 +0200] cupsdAcceptClient: Adding fd 14 to InputSet...
>d [10/Oct/2007:12:36:08 +0200] cupsdReadClient: 14, used=0, file=-1 state=0
>D [10/Oct/2007:12:36:08 +0200] cupsdReadClient: 14 POST / HTTP/1.1
>d [10/Oct/2007:12:36:08 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:36:08 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:36:08 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:36:08 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:36:08 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:36:08 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:36:08 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:36:08 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:36:08 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:36:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:36:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:36:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:36:08 +0200] POST /
>d [10/Oct/2007:12:36:08 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:36:08 +0200] cupsdReadClient: 14 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:36:08 +0200] cupsdProcessIPPRequest(0x802acf60[14]): operation_id = 000a
>D [10/Oct/2007:12:36:08 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:36:08 +0200] get_jobs(0x802acf60[14], ipp://localhost/jobs/)
>d [10/Oct/2007:12:36:08 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:36:08 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:36:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:36:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:36:08 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:36:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:36:08 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:36:08 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:36:08 +0200] add_job_state_reasons(0x802acf60[14], 198)
>d [10/Oct/2007:12:36:08 +0200] copy_attrs(to=0x802acbe8, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:36:08 +0200] copy_attribute(0x802acbe8, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:36:08 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)
>d [10/Oct/2007:12:36:08 +0200] cupsdProcessIPPRequest: Adding fd 14 to OutputSet...
>d [10/Oct/2007:12:36:08 +0200] cupsdWriteClient: 14 bytes < 0
>d [10/Oct/2007:12:36:08 +0200] cupsdWriteClient: Removing fd 14 from OutputSet...
>d [10/Oct/2007:12:36:08 +0200] cupsdReadClient: 14, used=0, file=-1 state=0
>d [10/Oct/2007:12:36:08 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:36:08 +0200] cupsdCloseClient: 14
>d [10/Oct/2007:12:36:08 +0200] cupsdCloseClient: Removing fd 14 from InputSet and OutputSet...
>d [10/Oct/2007:12:36:09 +0200] select_timeout: 1 seconds to send browse update
>d [10/Oct/2007:12:36:10 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:36:21 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:36:21 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:36:21 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:36:32 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:36:32 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:36:32 +0200] select_timeout: 9 seconds to send browse update
>d [10/Oct/2007:12:36:36 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:36:36 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:36:36 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:36:36 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:36:36 +0200] cupsdReadClient: 10, used=0, file=-1 state=0
>d [10/Oct/2007:12:36:36 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:36:36 +0200] cupsdCloseClient: 10
>d [10/Oct/2007:12:36:36 +0200] cupsdCloseClient: Removing fd 10 from InputSet and OutputSet...
>d [10/Oct/2007:12:36:37 +0200] select_timeout: 4 seconds to send browse update
>I [10/Oct/2007:12:36:38 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:36:38 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:36:38 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:36:38 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 1
>D [10/Oct/2007:12:36:38 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:36:38 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:36:38 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:36:38 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:36:38 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:36:38 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:36:38 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:36:38 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:36:38 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:36:38 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:36:38 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:36:38 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:36:38 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:36:38 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:36:38 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:36:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:36:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:36:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:36:38 +0200] POST /
>d [10/Oct/2007:12:36:38 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:36:38 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:36:38 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000a
>D [10/Oct/2007:12:36:38 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:36:38 +0200] get_jobs(0x802997b0[8], ipp://localhost/jobs/)
>d [10/Oct/2007:12:36:38 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:36:38 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:36:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:36:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:36:38 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:36:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:36:38 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:36:38 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:36:38 +0200] add_job_state_reasons(0x802997b0[8], 198)
>d [10/Oct/2007:12:36:38 +0200] copy_attrs(to=0x802acba8, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:36:38 +0200] copy_attribute(0x802acba8, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:36:38 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:36:38 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:36:38 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:36:38 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:36:38 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:36:38 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:36:38 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:36:38 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:36:39 +0200] select_timeout: 2 seconds to send browse update
>d [10/Oct/2007:12:36:41 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:36:52 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:36:52 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:36:52 +0200] stringpool: 417 strings, 8456 allocated, 8832 total bytes
>d [10/Oct/2007:12:36:52 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:37:03 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:37:03 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:37:03 +0200] select_timeout: 9 seconds to send browse update
>I [10/Oct/2007:12:37:08 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:37:08 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:37:08 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:37:08 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 1
>D [10/Oct/2007:12:37:08 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:37:08 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:37:08 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:37:08 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:37:08 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:37:08 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:37:08 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:37:08 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:37:08 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:37:08 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:37:08 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:37:08 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:37:08 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:37:08 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:37:08 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:37:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:37:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:37:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:37:08 +0200] POST /
>d [10/Oct/2007:12:37:08 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:37:08 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:37:08 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000a
>D [10/Oct/2007:12:37:08 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:37:08 +0200] get_jobs(0x802997b0[8], ipp://localhost/jobs/)
>d [10/Oct/2007:12:37:08 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:37:08 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:37:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:37:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:37:08 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:37:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:37:08 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:37:08 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:37:08 +0200] add_job_state_reasons(0x802997b0[8], 198)
>d [10/Oct/2007:12:37:08 +0200] copy_attrs(to=0x8029d288, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:37:08 +0200] copy_attribute(0x8029d288, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:37:08 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:37:08 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:37:08 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:37:08 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:37:08 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:37:08 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:37:08 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:37:08 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:37:09 +0200] select_timeout: 3 seconds to send browse update
>d [10/Oct/2007:12:37:12 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:37:23 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:37:23 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:37:23 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:37:34 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:37:34 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:37:34 +0200] select_timeout: 9 seconds to send browse update
>I [10/Oct/2007:12:37:38 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:37:38 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:37:38 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:37:38 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 1
>D [10/Oct/2007:12:37:38 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:37:38 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:37:38 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:37:38 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:37:38 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:37:38 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:37:38 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:37:38 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:37:38 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:37:38 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:37:38 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:37:38 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:37:38 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:37:38 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:37:38 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:37:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:37:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:37:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:37:38 +0200] POST /
>d [10/Oct/2007:12:37:38 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:37:38 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:37:38 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000a
>D [10/Oct/2007:12:37:38 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:37:38 +0200] get_jobs(0x802997b0[8], ipp://localhost/jobs/)
>d [10/Oct/2007:12:37:38 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:37:38 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:37:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:37:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:37:38 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:37:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:37:38 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:37:38 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:37:38 +0200] add_job_state_reasons(0x802997b0[8], 198)
>d [10/Oct/2007:12:37:38 +0200] copy_attrs(to=0x802a8b98, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:37:38 +0200] copy_attribute(0x802a8b98, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:37:38 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:37:38 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:37:38 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:37:38 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:37:38 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:37:38 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:37:38 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:37:38 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:37:39 +0200] select_timeout: 4 seconds to send browse update
>d [10/Oct/2007:12:37:43 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:37:54 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:37:54 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:37:54 +0200] stringpool: 439 strings, 8456 allocated, 9392 total bytes
>d [10/Oct/2007:12:37:54 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:38:05 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:38:05 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:38:05 +0200] select_timeout: 9 seconds to send browse update
>I [10/Oct/2007:12:38:08 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:38:08 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:38:08 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:38:08 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 1
>D [10/Oct/2007:12:38:08 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:38:08 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:38:08 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:38:08 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:38:08 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:38:08 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:38:08 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:38:08 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:38:08 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:38:08 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:38:08 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:38:08 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:38:08 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:38:08 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:38:08 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:38:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:38:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:38:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:38:08 +0200] POST /
>d [10/Oct/2007:12:38:08 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:38:08 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:38:08 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000a
>D [10/Oct/2007:12:38:08 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:38:08 +0200] get_jobs(0x802997b0[8], ipp://localhost/jobs/)
>d [10/Oct/2007:12:38:08 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:38:08 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:38:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:38:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:38:08 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:38:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:38:08 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:38:08 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:38:08 +0200] add_job_state_reasons(0x802997b0[8], 198)
>d [10/Oct/2007:12:38:08 +0200] copy_attrs(to=0x8029d260, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:38:08 +0200] copy_attribute(0x8029d260, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:38:08 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:38:08 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:38:08 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:38:08 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:38:08 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:38:08 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:38:08 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:38:08 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:38:09 +0200] select_timeout: 5 seconds to send browse update
>d [10/Oct/2007:12:38:14 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:38:25 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:38:25 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:38:25 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:38:36 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:38:36 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:38:36 +0200] select_timeout: 9 seconds to send browse update
>I [10/Oct/2007:12:38:38 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:38:38 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:38:38 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:38:38 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 1
>D [10/Oct/2007:12:38:38 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:38:38 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:38:38 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:38:38 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:38:38 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:38:38 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:38:38 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:38:38 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:38:38 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:38:38 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:38:38 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:38:38 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:38:38 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:38:38 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:38:38 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:38:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:38:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:38:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:38:38 +0200] POST /
>d [10/Oct/2007:12:38:38 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:38:38 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:38:38 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000a
>D [10/Oct/2007:12:38:38 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:38:38 +0200] get_jobs(0x802997b0[8], ipp://localhost/jobs/)
>d [10/Oct/2007:12:38:38 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:38:38 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:38:38 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:38:38 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:38:38 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:38:38 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:38:38 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:38:38 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:38:38 +0200] add_job_state_reasons(0x802997b0[8], 198)
>d [10/Oct/2007:12:38:38 +0200] copy_attrs(to=0x802acb80, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:38:38 +0200] copy_attribute(0x802acb80, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:38:38 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:38:38 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:38:38 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:38:38 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:38:39 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:38:39 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:38:39 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:38:39 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:38:40 +0200] select_timeout: 5 seconds to send browse update
>d [10/Oct/2007:12:38:45 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:38:56 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:38:56 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:38:56 +0200] stringpool: 461 strings, 8456 allocated, 9952 total bytes
>d [10/Oct/2007:12:38:56 +0200] cupsdDeleteCert: removing certificate for pid 0
>d [10/Oct/2007:12:38:56 +0200] cupsdAddCert: adding certificate for pid 0
>d [10/Oct/2007:12:38:56 +0200] cupsdAddCert: NumSystemGroups=2
>d [10/Oct/2007:12:38:56 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:39:07 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:39:07 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:39:07 +0200] select_timeout: 9 seconds to send browse update
>I [10/Oct/2007:12:39:08 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:39:08 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:39:08 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:39:08 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 1
>D [10/Oct/2007:12:39:08 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:39:08 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:39:08 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:39:08 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:39:08 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:39:08 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:39:08 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:39:08 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:39:08 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:39:08 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:39:08 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:39:08 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:39:08 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:39:08 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:39:08 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:39:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:39:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:39:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:39:08 +0200] POST /
>d [10/Oct/2007:12:39:08 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:39:08 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:39:08 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000a
>D [10/Oct/2007:12:39:08 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:39:08 +0200] get_jobs(0x802997b0[8], ipp://localhost/jobs/)
>d [10/Oct/2007:12:39:08 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:39:08 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:39:08 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:39:08 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:39:08 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:39:08 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:39:08 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:39:08 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:39:08 +0200] add_job_state_reasons(0x802997b0[8], 198)
>d [10/Oct/2007:12:39:08 +0200] copy_attrs(to=0x802ace18, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:39:08 +0200] copy_attribute(0x802ace18, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:39:08 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:39:08 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:39:08 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:39:08 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:39:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:39:09 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:39:09 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:39:09 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:39:10 +0200] select_timeout: 6 seconds to send browse update
>d [10/Oct/2007:12:39:16 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:39:27 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:39:27 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:39:27 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:39:38 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:39:38 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:39:38 +0200] select_timeout: 9 seconds to send browse update
>I [10/Oct/2007:12:39:38 +0200] [Job 198] Printer not connected; will retry in 30 seconds...
>d [10/Oct/2007:12:39:38 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x8026d9b0(samsung), job=(nil)(0), text="Printer "%s" state changed.", ...)
>D [10/Oct/2007:12:39:38 +0200] Discarding unused printer-state-changed event...
>d [10/Oct/2007:12:39:39 +0200] cupsdAcceptClient(lis=0x80255760) 4 Clients = 1
>D [10/Oct/2007:12:39:39 +0200] cupsdAcceptClient: skipping getpeercon()
>D [10/Oct/2007:12:39:39 +0200] cupsdAcceptClient: 8 from localhost (Domain)
>d [10/Oct/2007:12:39:39 +0200] cupsdAcceptClient: 8 connected to server on localhost:631
>d [10/Oct/2007:12:39:39 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
>d [10/Oct/2007:12:39:39 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>D [10/Oct/2007:12:39:39 +0200] cupsdReadClient: 8 POST / HTTP/1.1
>d [10/Oct/2007:12:39:39 +0200] cupsdFindBest: uri = "/"...
>d [10/Oct/2007:12:39:39 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
>d [10/Oct/2007:12:39:39 +0200] cupsdFindBest: Location /admin/conf Limit 7f
>d [10/Oct/2007:12:39:39 +0200] cupsdFindBest: Location /admin Limit 7f
>d [10/Oct/2007:12:39:39 +0200] cupsdFindBest: Location / Limit 7f
>d [10/Oct/2007:12:39:39 +0200] cupsdFindBest: best = /
>d [10/Oct/2007:12:39:39 +0200] cupsdAuthorize: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:39:39 +0200] cupsdAuthorize: Authorization=""
>D [10/Oct/2007:12:39:39 +0200] cupsdAuthorize: No authentication data provided.
>d [10/Oct/2007:12:39:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802558f0(/)
>d [10/Oct/2007:12:39:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:39:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:39:39 +0200] POST /
>d [10/Oct/2007:12:39:39 +0200] CONTENT_TYPE = application/ipp
>d [10/Oct/2007:12:39:39 +0200] cupsdReadClient: 8 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
>d [10/Oct/2007:12:39:39 +0200] cupsdProcessIPPRequest(0x802997b0[8]): operation_id = 000a
>D [10/Oct/2007:12:39:39 +0200] Get-Jobs ipp://localhost/jobs/
>d [10/Oct/2007:12:39:39 +0200] get_jobs(0x802997b0[8], ipp://localhost/jobs/)
>d [10/Oct/2007:12:39:39 +0200] cupsdFindPolicyOp(p=0x80255ab8, op=a(Get-Jobs))
>d [10/Oct/2007:12:39:39 +0200] cupsdFindPolicyOp: Found wildcard match...
>d [10/Oct/2007:12:39:39 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x802739d0((null))
>d [10/Oct/2007:12:39:39 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
>d [10/Oct/2007:12:39:39 +0200] cupsdIsAuthorized: op=0(unknown-0000)
>d [10/Oct/2007:12:39:39 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
>d [10/Oct/2007:12:39:39 +0200] get_jobs: job->id = 198
>d [10/Oct/2007:12:39:39 +0200] get_jobs: count = 1
>d [10/Oct/2007:12:39:39 +0200] add_job_state_reasons(0x802997b0[8], 198)
>d [10/Oct/2007:12:39:39 +0200] copy_attrs(to=0x802a93b0, from=0x8029cea8, ra=(nil), group=2, quickcopy=0)
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029f578[printer-uri,2,45])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8027cce8[job-originating-user-name,2,42])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8027cd10[job-name,2,42])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029cfe0[document-format,2,49])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029d008[job-hold-until,2,42])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029d308[job-priority,2,21])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8027cc98[job-uuid,2,45])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029d0f8[job-originating-host-name,2,42])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029d140[time-at-creation,2,21])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029d180[time-at-processing,2,21])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8029d1c0[time-at-completed,2,13])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x802a0060[job-id,2,21])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x802a0088[job-state,2,23])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x802a00b0[job-media-sheets-completed,2,21])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x802a00f8[job-printer-uri,2,45])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x802a0138[job-name,2,42])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x802a0160[job-k-octets,2,21])
>d [10/Oct/2007:12:39:39 +0200] copy_attribute(0x802a93b0, 0x8027cdf0[job-sheets,2,42])
>D [10/Oct/2007:12:39:39 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
>d [10/Oct/2007:12:39:39 +0200] cupsdProcessIPPRequest: Adding fd 8 to OutputSet...
>d [10/Oct/2007:12:39:39 +0200] cupsdWriteClient: 8 bytes < 0
>d [10/Oct/2007:12:39:39 +0200] cupsdWriteClient: Removing fd 8 from OutputSet...
>d [10/Oct/2007:12:39:39 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
>d [10/Oct/2007:12:39:39 +0200] cupsdReadClient: httpGets returned EOF...
>D [10/Oct/2007:12:39:39 +0200] cupsdCloseClient: 8
>d [10/Oct/2007:12:39:39 +0200] cupsdCloseClient: Removing fd 8 from InputSet and OutputSet...
>d [10/Oct/2007:12:39:40 +0200] select_timeout: 7 seconds to send browse update
>d [10/Oct/2007:12:39:47 +0200] select_timeout: 11 seconds to process active jobs
>d [10/Oct/2007:12:39:58 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
>d [10/Oct/2007:12:39:58 +0200] cupsdCheckJobs: Job 198: state_value=5, loaded=yes
>d [10/Oct/2007:12:39:58 +0200] stringpool: 478 strings, 8456 allocated, 10416 total bytes
>d [10/Oct/2007:12:39:58 +0200] select_timeout: 11 seconds to process active jobs

Actions: View

Attachments on bug 308691: 221431 | 221441 | 221461 | 221471 | 221481 | 222321