[Pkg-cups-devel] Bug#663995: cups: Kerberos authentication doesn't work for admin interface
Sam Morris
sam at robots.org.uk
Wed Mar 14 16:22:22 UTC 2012
Package: cups
Version: 1.5.2-7
Severity: normal
I'm trying to access the CUPS admin interface via a web browser. I can
see the browser correctly getting a ticket for HTTP/host.example.com,
and a corresponding entry is available in the server's keytab, however
all protected pages merely display the Unauthorized message saying that
I need to have a valid ticket.
cupsd.conf:
ServerAlias host.office.example.com
LogLevel warn
MaxLogSize 0
SystemGroup lpadmin
Port 631
Listen /var/run/cups/cups.sock
Browsing On
BrowseOrder allow,deny
BrowseRemoteProtocols
BrowseAddress @LOCAL
BrowseLocalProtocols CUPS dnssd
<Location />
Order allow,deny
Allow @LOCAL
</Location>
<Location /admin>
Order allow,deny
Allow @LOCAL
</Location>
<Location /admin/conf>
AuthType Default
Require user @SYSTEM
Order allow,deny
Allow @LOCAL
</Location>
<Policy default>
<Limit Send-Document Send-URI Hold-Job Release-Job Restart-Job Purge-Jobs Set-Job-Attributes Create-Job-Subscription Renew-Subscription Cancel-Subscription Get-Notifications Reprocess-Job Cancel-Current-Job Suspend-Current-Job Resume-Job CUPS-Move-Job CUPS-Get-Document>
Require user @OWNER @SYSTEM
Order deny,allow
</Limit>
<Limit CUPS-Add-Modify-Printer CUPS-Delete-Printer CUPS-Add-Modify-Class CUPS-Delete-Class CUPS-Set-Default CUPS-Get-Devices>
AuthType Default
Require user @SYSTEM
Order deny,allow
</Limit>
<Limit Pause-Printer Resume-Printer Enable-Printer Disable-Printer Pause-Printer-After-Current-Job Hold-New-Jobs Release-Held-New-Jobs Deactivate-Printer Activate-Printer Restart-Printer Shutdown-Printer Startup-Printer Promote-Job Schedule-Job-After CUPS-Accept-Jobs CUPS-Reject-Jobs>
AuthType Default
Require user @SYSTEM
Order deny,allow
</Limit>
<Limit CUPS-Authenticate-Job>
Require user @OWNER @SYSTEM
Order deny,allow
</Limit>
<Limit All>
Order deny,allow
</Limit>
</Policy>
<Policy authenticated>
<Limit Create-Job Print-Job Print-URI>
AuthType Default
Order deny,allow
</Limit>
<Limit Send-Document Send-URI Hold-Job Release-Job Restart-Job Purge-Jobs Set-Job-Attributes Create-Job-Subscription Renew-Subscription Cancel-Subscription Get-Notifications Reprocess-Job Cancel-Current-Job Suspend-Current-Job Resume-Job CUPS-Move-Job CUPS-Get-Document>
AuthType Default
Require user @OWNER @SYSTEM
Order deny,allow
</Limit>
<Limit CUPS-Add-Modify-Printer CUPS-Delete-Printer CUPS-Add-Modify-Class CUPS-Delete-Class CUPS-Set-Default>
AuthType Default
Require user @SYSTEM
Order deny,allow
</Limit>
<Limit Pause-Printer Resume-Printer Enable-Printer Disable-Printer Pause-Printer-After-Current-Job Hold-New-Jobs Release-Held-New-Jobs Deactivate-Printer Activate-Printer Restart-Printer Shutdown-Printer Startup-Printer Promote-Job Schedule-Job-After CUPS-Accept-Jobs CUPS-Reject-Jobs>
AuthType Default
Require user @SYSTEM
Order deny,allow
</Limit>
<Limit Cancel-Job CUPS-Authenticate-Job>
AuthType Default
Require user @OWNER @SYSTEM
Order deny,allow
</Limit>
<Limit All>
Order deny,allow
</Limit>
</Policy>
DefaultAuthType Negotiate
I'm in the lpadmin group:
$ getent group lpadmin
lpadmin:x:113:sam
A debug2 log of an authentication attempt is attached. You can see me
correctly being authenticated via kerberos as sam at EXAMPLE.COM, but then
there is the message "Authorized using Basic, expected Negotiate"
followed by the Unauthorized response.
The KDCs are running MIT Kerberos 1.10.
-- System Information:
Debian Release: wheezy/sid
APT prefers testing
APT policy: (530, 'testing'), (520, 'unstable'), (510, 'experimental')
Architecture: amd64 (x86_64)
Kernel: Linux 3.2.0-1-amd64 (SMP w/2 CPU cores)
Locale: LANG=en_GB.utf8, LC_CTYPE=en_GB.utf8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Versions of packages cups depends on:
ii adduser 3.113+nmu1
ii bc 1.06.95-2+b1
ii cups-client 1.5.2-7
ii cups-common 1.5.2-5
ii cups-filters 1.0.2-1
ii cups-ppdc 1.5.2-5
ii debconf [debconf-2.0] 1.5.41
ii dpkg 1.16.1.2
ii ghostscript 9.05~dfsg-3
ii libavahi-client3 0.6.31-1
ii libavahi-common3 0.6.31-1
ii libc6 2.13-27
ii libcups2 1.5.2-5
ii libcupscgi1 1.5.2-5
ii libcupsimage2 1.5.2-5
ii libcupsmime1 1.5.2-5
ii libcupsppdc1 1.5.2-5
ii libdbus-1-3 1.4.18-1
ii libgcc1 1:4.6.3-1
ii libgnutls26 2.12.17-2
ii libgssapi-krb5-2 1.10+dfsg~beta1-2
ii libkrb5-3 1.10+dfsg~beta1-2
ii libldap-2.4-2 2.4.28-1.1
ii libpam0g 1.1.3-7
ii libpaper1 1.1.24+nmu1
ii libslp1 1.2.1-9
ii libstdc++6 4.6.3-1
ii libusb-1.0-0 2:1.0.9~rc3-3
ii lsb-base 3.2-28.1
ii poppler-utils 0.16.7-3
ii procps 1:3.3.2-3
ii ssl-cert 1.0.28
Versions of packages cups recommends:
pn avahi-daemon 0.6.31-1
pn colord 0.1.16-2
pn cups-driver-gutenprint <none>
pn foomatic-filters <none>
pn ghostscript-cups <none>
Versions of packages cups suggests:
ii cups-bsd 1.5.2-7
ii cups-pdf <none>
ii foomatic-db-compressed-ppds | foomatic-db <none>
ii hplip <none>
ii smbclient 2:3.6.3-1
ii udev 175-3.1
-- Configuration Files:
/etc/cups/cupsd.conf [Errno 13] Permission denied: u'/etc/cups/cupsd.conf'
-- debconf information excluded
-------------- next part --------------
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 POST /admin/ HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/admin/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /admin
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/admin/", con->best=0x7f0f755714b0(/admin)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization=""
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: No authentication data provided.
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0x7f0f755714b0(/admin)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] pipe_command(con=0x7f0f756d0b90(16), infile=19, outfile=0x7f0f756d4260, command="/usr/lib/cups/cgi-bin/admin.cgi", options="(null)", root=0)
D [14/Mar/2012:16:14:27 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[10] = "SERVER_ADMIN=root at host.example.com"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.2"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[13] = "TZ=Europe/London"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[14] = "USER=root"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[18] = "LANG=en_US.UTF8"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[21] = "SERVER_NAME=host.office.example.com"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[22] = "SERVER_PORT=631"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[23] = "REMOTE_ADDR=10.0.1.121"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[24] = "REMOTE_HOST=10.0.1.121"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[25] = "SCRIPT_NAME=/admin/"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=2d0c5f698ed0cce8d4b59250faca8a46"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:10.0.2) Gecko/20100101 Firefox/10.0.2 Iceweasel/10.0.2"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[30] = "HTTP_REFERER=https://host.office.example.com:631/printers/delly"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[31] = "REQUEST_METHOD=POST"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[32] = "CONTENT_LENGTH=82"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[33] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[34] = "HTTPS=ON"
d [14/Mar/2012:16:14:27 +0000] File "/usr/lib/cups/cgi-bin/admin.cgi" permissions OK (040755/uid=0/gid=0).
d [14/Mar/2012:16:14:27 +0000] cupsdStartProcess(command="/usr/lib/cups/cgi-bin/admin.cgi", argv=0x7fffb7a4bc60, envp=0x7fffb7a4b8a0, infd=19, outfd=21, errfd=15, backfd=-1, sidefd=-1, root=0, profile=(nil), job=(nil)(0), pid=0x7fffb7a4bf8c) = 59572
D [14/Mar/2012:16:14:27 +0000] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 59572)
I [14/Mar/2012:16:14:27 +0000] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=59572)
D [14/Mar/2012:16:14:27 +0000] cupsdSendCommand: 16 file=20
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=20, read_cb=0x7f0f73a75a30, write_cb=(nil), data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
D [14/Mar/2012:16:14:27 +0000] [CGI] admin.cgi started...
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=3
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 19 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=19, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756f48c0)
D [14/Mar/2012:16:14:27 +0000] [CGI] http=0x7f2e8fc8e250
D [14/Mar/2012:16:14:27 +0000] [CGI] org.cups.sid cookie is "2d0c5f698ed0cce8d4b59250faca8a46"
D [14/Mar/2012:16:14:27 +0000] [CGI] op="modify-printer"...
D [14/Mar/2012:16:14:27 +0000] [CGI] do_am_printer: DEVICE_URI="(null)"
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 19 POST / HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization=""
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: No authentication data provided.
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=113, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=113, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=84, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=84, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=47, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=47, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest(0x7f0f756f48c0[19]): operation_id = 000b
D [14/Mar/2012:16:14:27 +0000] Get-Printer-Attributes ipp://localhost/printers/delly
d [14/Mar/2012:16:14:27 +0000] get_printer_attrs(0x7f0f756f48c0[19], ipp://localhost/printers/delly)
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=b(Get-Printer-Attributes))
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp: Found wildcard match...
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755758b0((null))
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: op=0(0x0000)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] printer-icons="http://localhost:631/icons/delly.png"
d [14/Mar/2012:16:14:27 +0000] add_printer_state_reasons(0x7f0f756f48c0[19], 0x7f0f755937d0[delly])
d [14/Mar/2012:16:14:27 +0000] printer-uri-supported="ipp://localhost:631/printers/delly"
d [14/Mar/2012:16:14:27 +0000] add_queued_job_count(0x7f0f756f48c0[19], 0x7f0f755937d0[delly])
d [14/Mar/2012:16:14:27 +0000] copy_attrs(to=0x7f0f756e92c0, from=0x7f0f755840f0, ra=(nil), group=0, quickcopy=0)
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584140[uri-authentication-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755841e0[uri-security-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584250[printer-name,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584290[printer-location,4,41])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755842f0[printer-info,4,41])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584350[printer-uuid,4,45])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755843b0[job-quota-period,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584410[job-k-limit,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584470[job-page-limit,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755844d0[auth-info-required,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584540[job-sheets-default,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755845c0[device-uri,4,45])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585000[document-format-supported,4,49])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585420[copies-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585460[document-format-default,4,49])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755854a0[job-hold-until-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585500[job-priority-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585540[number-up-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585580[notify-lease-duration-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755855c0[notify-events-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585620[orientation-requested-default,4,13])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75585660[print-quality-default,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attrs(to=0x7f0f756e92c0, from=0x7f0f75594dd0, ra=(nil), group=0, quickcopy=0)
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594e20[color-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594e80[pages-per-minute,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594ee0[pages-per-minute-color,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594f50[print-quality-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594fc0[printer-make-and-model,4,41])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75595030[media-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755b9160[media-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755b9340[media-source-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755b9430[media-type-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755b9100[media-bottom-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755b9680[media-left-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755b9770[media-right-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755b98d0[media-top-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75636dc0[output-bin-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75636e80[output-bin-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75636ec0[output-mode-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75636f80[output-mode-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75636fc0[print-color-mode-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75637080[print-color-mode-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f756370c0[printer-resolution-supported,4,32])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75637180[printer-resolution-default,4,32])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f756371c0[sides-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75637280[sides-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75637320[printer-commands,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f756372c0[port-monitor,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75637420[port-monitor-supported,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f756374e0[finishings-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75637520[finishings-default,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attrs(to=0x7f0f756e92c0, from=0x7f0f75589b40, ra=(nil), group=0, quickcopy=-2147483648)
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583f90[charset-configured,4,80000047])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583f00[charset-supported,4,80000047])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583e80[compression-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583f50[copies-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583d30[cups-version,4,80000041])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583d70[generated-natural-language-supported,4,48])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583ac0[ipp-versions-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583b60[ippget-event-life,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755942b0[job-creation-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594440[job-hold-until-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75593650[job-ids-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755935e0[job-k-octets-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755934e0[job-priority-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594670[job-settable-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755947e0[job-sheets-supported,4,80000042])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75593400[jpeg-k-octets-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755933c0[jpeg-x-dimension-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594990[jpeg-y-dimension-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594a00[media-col-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75593fc0[multiple-document-handling-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755948b0[multiple-document-jobs-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755947a0[multiple-operation-time-out,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594600[natural-language-configured,4,48])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583df0[notify-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594590[notify-lease-duration-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594520[notify-max-events-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583770[notify-events-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75594400[notify-pull-method-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f756587e0[notify-schemes-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f755936c0[number-up-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75662e60[operations-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75663180[orientation-requested-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f756631f0[page-ranges-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75663230[pdf-k-octets-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75583c40[pdf-versions-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75663270[pdl-override-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f756632b0[printer-op-policy-supported,4,80000042])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75663300[printer-settable-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75663390[server-is-sharing-printers,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756e92c0, 0x7f0f75584000[which-jobs-supported,4,80000044])
D [14/Mar/2012:16:14:27 +0000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/delly) from localhost
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest: ippLength(response)=7964
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=19, read_cb=0x7f0f73a78940, write_cb=0x7f0f73a782f0, data=0x7f0f756f48c0)
d [14/Mar/2012:16:14:27 +0000] select_timeout(0): 1 seconds to send browse update
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(0), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756f48c0(19)) response=0x7f0f756e92c0(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=19, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756f48c0)
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [14/Mar/2012:16:14:27 +0000] [CGI] Getting list of devices...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 19 POST / HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization=""
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: No authentication data provided.
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=83, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=83, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=54, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=54, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=17, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=17, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 19 1.1 CUPS-Get-Devices 1
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest(0x7f0f756f48c0[19]): operation_id = 400b
D [14/Mar/2012:16:14:27 +0000] CUPS-Get-Devices
d [14/Mar/2012:16:14:27 +0000] get_devices(0x7f0f756f48c0[19])
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=400b(CUPS-Get-Devices))
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f75573f20((null))
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Negotiate, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: op=400b(CUPS-Get-Devices)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: username=""
D [14/Mar/2012:16:14:27 +0000] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
d [14/Mar/2012:16:14:27 +0000] cupsdSendError(con=0x7f0f756f48c0(19), code=401, auth_type=0
D [14/Mar/2012:16:14:27 +0000] cupsdSendHeader: 19 WWW-Authenticate: Basic realm="CUPS"
d [14/Mar/2012:16:14:27 +0000] select_timeout(0): 1 seconds to send browse update
D [14/Mar/2012:16:14:27 +0000] [CGI] cgi_passwd(prompt="Password for lp on localhost? ") called!
d [14/Mar/2012:16:14:27 +0000] write_pipe(con=0x7f0f756d0b90(16)) CGI output on fd 20
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=20)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=(nil), write_cb=0x7f0f73a782f0, data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=(nil)(-1), file=20 pipe_pid=59572 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=20, read_cb=0x7f0f73a75a30, write_cb=(nil), data=0x7f0f756d0b90)
D [14/Mar/2012:16:14:27 +0000] Script header: Status: 401
d [14/Mar/2012:16:14:27 +0000] cupsdSendError(con=0x7f0f756d0b90(16), code=401, auth_type=0
D [14/Mar/2012:16:14:27 +0000] cupsdSendHeader: 16 WWW-Authenticate: Negotiate
D [14/Mar/2012:16:14:27 +0000] Script header:
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=20)
d [14/Mar/2012:16:14:27 +0000] cupsdEndProcess(pid=59572, force=0)
D [14/Mar/2012:16:14:27 +0000] cupsdWriteClient: 16 Closing because Keep-Alive disabled
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 16
D [14/Mar/2012:16:14:27 +0000] SSL shutdown successful!
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] process_children()
d [14/Mar/2012:16:14:27 +0000] cupsdFinishProcess(pid=59572, name=0x7fffb7a66710, namelen=1024, job_id=0x7fffb7a6670c(0)) = "/usr/lib/cups/cgi-bin/admin.cgi"
D [14/Mar/2012:16:14:27 +0000] PID 59572 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 16
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=16)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756f48c0(19)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 19 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 19
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=19)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756dc220(17)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=2147483647, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 17 POST /admin/ HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/admin/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /admin
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/admin/", con->best=0x7f0f755714b0(/admin)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization="Negotiate YIICzgYGKwYBBQUCoIICwjCCAr6gJzAlBgkqhkiG9xIBAgIGBSsFAQUCBgkqhkiC9xIBAgIGBisGAQUCBaKCApEEggKNYIICiQYJKoZIhvcSAQICAQBuggJ4MIICdKADAgEFoQMCAQ6iBwMFAAAAAACjggGHYYIBgzCCAX+gAwIBBaEUGxJSRUQtUkVERU1QVElPTi5DT02iMjAwoAMCAQOhKTAnGwRIVFRQGx90aG90aC5vZmZpY2UucmVkLXJlZGVtcHRpb24uY29to4IBLDCCASigAwIBEqEDAgECooIBGgSCARZMJ8+PMY+x7pD11depKxYI82vt5HXmg9PAXjAIYhcFi0PwLxX55KoMpRgaJ85LgR6ZlFC2ZUZpGoLp/1+3RcnHwReqTB9MsF9oHnT4hFKt0usNRjF3az+tTpWu7hvA5JG+tsfiVsBr8oOgGNEUC6IlnhfKFpsLcRtN1asy92AXymLMy7WnkfBBYKiWH6sxY8y6NBn0CmU95gnLA31XG7kWy14F5bshdJGd3lMUL/zVdlgPWbt3OB8P7gaq+zs7rGIE/sqGT0eGuGGt6gK8SsyERDf32WFgYMgvJG8d2cpmlMwvZHfhkZZt5uNSYjoucqbVTiFs9198Cn04Fnab0OwpJBTbthBontsM5cZwlQO46br4z0UjN6SB0zCB0KADAgESooHIBIHFb0en62nYiM5dONYByXpvngqzLuS6RMghAzIxDqeLY1QNyNLBMuAuRb7jhpMCFoMfnNIbTdiN4U8Yfx3mSrAjdAMGI7Qm7G0ttAgcUIGp3ePY8RVgPKng5bxFfT/dJf73HwaMGKGXUEA04Vikl4AJ+z/h2QIdCtsfn8JpsA54fdPLhpuekbLZrvNmkKKdRBjiHONM8T48lK4OvM3Ii6UpjxobPNj6+3jt2G4bkr5/NZ3RKRd1OWamckBI3JazZbXAQcgSEio="
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorized as sam at EXAMPLE.COM using Negotiate
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0x7f0f755714b0(/admin)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] pipe_command(con=0x7f0f756dc220(17), infile=16, outfile=0x7f0f756df8f0, command="/usr/lib/cups/cgi-bin/admin.cgi", options="(null)", root=0)
D [14/Mar/2012:16:14:27 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[10] = "SERVER_ADMIN=root at host.example.com"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.2"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[13] = "TZ=Europe/London"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[14] = "USER=root"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[18] = "AUTH_TYPE=Negotiate"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[22] = "SERVER_NAME=host.office.example.com"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[24] = "REMOTE_ADDR=10.0.1.121"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[25] = "REMOTE_HOST=10.0.1.121"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[26] = "SCRIPT_NAME=/admin/"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[28] = "REMOTE_USER=sam at EXAMPLE.COM"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=2d0c5f698ed0cce8d4b59250faca8a46"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:10.0.2) Gecko/20100101 Firefox/10.0.2 Iceweasel/10.0.2"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[32] = "HTTP_REFERER=https://host.office.example.com:631/printers/delly"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[34] = "CONTENT_LENGTH=82"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [14/Mar/2012:16:14:27 +0000] [CGI] envp[36] = "HTTPS=ON"
d [14/Mar/2012:16:14:27 +0000] File "/usr/lib/cups/cgi-bin/admin.cgi" permissions OK (040755/uid=0/gid=0).
d [14/Mar/2012:16:14:27 +0000] cupsdStartProcess(command="/usr/lib/cups/cgi-bin/admin.cgi", argv=0x7fffb7a4bc60, envp=0x7fffb7a4b8a0, infd=16, outfd=20, errfd=15, backfd=-1, sidefd=-1, root=0, profile=(nil), job=(nil)(0), pid=0x7fffb7a4bf8c) = 59573
d [14/Mar/2012:16:14:27 +0000] cupsdAddCert: Adding certificate for PID 59573
D [14/Mar/2012:16:14:27 +0000] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 59573)
I [14/Mar/2012:16:14:27 +0000] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=59573)
D [14/Mar/2012:16:14:27 +0000] cupsdSendCommand: 17 file=19
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=19, read_cb=0x7f0f73a75a30, write_cb=(nil), data=0x7f0f756dc220)
D [14/Mar/2012:16:14:27 +0000] [CGI] admin.cgi started...
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=2
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 16 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d0b90)
D [14/Mar/2012:16:14:27 +0000] [CGI] http=0x7f87b9f3e250
D [14/Mar/2012:16:14:27 +0000] [CGI] org.cups.sid cookie is "2d0c5f698ed0cce8d4b59250faca8a46"
D [14/Mar/2012:16:14:27 +0000] [CGI] op="modify-printer"...
D [14/Mar/2012:16:14:27 +0000] [CGI] do_am_printer: DEVICE_URI="(null)"
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization=""
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: No authentication data provided.
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=113, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=113, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=84, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=84, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=47, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=47, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest(0x7f0f756d0b90[16]): operation_id = 000b
D [14/Mar/2012:16:14:27 +0000] Get-Printer-Attributes ipp://localhost/printers/delly
d [14/Mar/2012:16:14:27 +0000] get_printer_attrs(0x7f0f756d0b90[16], ipp://localhost/printers/delly)
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=b(Get-Printer-Attributes))
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp: Found wildcard match...
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755758b0((null))
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: op=0(0x0000)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] printer-icons="http://localhost:631/icons/delly.png"
d [14/Mar/2012:16:14:27 +0000] add_printer_state_reasons(0x7f0f756d0b90[16], 0x7f0f755937d0[delly])
d [14/Mar/2012:16:14:27 +0000] printer-uri-supported="ipp://localhost:631/printers/delly"
d [14/Mar/2012:16:14:27 +0000] add_queued_job_count(0x7f0f756d0b90[16], 0x7f0f755937d0[delly])
d [14/Mar/2012:16:14:27 +0000] copy_attrs(to=0x7f0f756cde00, from=0x7f0f755840f0, ra=(nil), group=0, quickcopy=0)
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584140[uri-authentication-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755841e0[uri-security-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584250[printer-name,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584290[printer-location,4,41])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755842f0[printer-info,4,41])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584350[printer-uuid,4,45])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755843b0[job-quota-period,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584410[job-k-limit,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584470[job-page-limit,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755844d0[auth-info-required,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584540[job-sheets-default,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755845c0[device-uri,4,45])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585000[document-format-supported,4,49])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585420[copies-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585460[document-format-default,4,49])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755854a0[job-hold-until-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585500[job-priority-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585540[number-up-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585580[notify-lease-duration-default,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755855c0[notify-events-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585620[orientation-requested-default,4,13])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75585660[print-quality-default,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attrs(to=0x7f0f756cde00, from=0x7f0f75594dd0, ra=(nil), group=0, quickcopy=0)
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594e20[color-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594e80[pages-per-minute,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594ee0[pages-per-minute-color,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594f50[print-quality-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594fc0[printer-make-and-model,4,41])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75595030[media-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755b9160[media-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755b9340[media-source-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755b9430[media-type-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755b9100[media-bottom-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755b9680[media-left-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755b9770[media-right-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755b98d0[media-top-margin-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75636dc0[output-bin-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75636e80[output-bin-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75636ec0[output-mode-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75636f80[output-mode-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75636fc0[print-color-mode-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75637080[print-color-mode-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f756370c0[printer-resolution-supported,4,32])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75637180[printer-resolution-default,4,32])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f756371c0[sides-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75637280[sides-default,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75637320[printer-commands,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f756372c0[port-monitor,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75637420[port-monitor-supported,4,42])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f756374e0[finishings-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75637520[finishings-default,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attrs(to=0x7f0f756cde00, from=0x7f0f75589b40, ra=(nil), group=0, quickcopy=-2147483648)
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583f90[charset-configured,4,80000047])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583f00[charset-supported,4,80000047])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583e80[compression-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583f50[copies-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583d30[cups-version,4,80000041])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583d70[generated-natural-language-supported,4,48])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583ac0[ipp-versions-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583b60[ippget-event-life,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755942b0[job-creation-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594440[job-hold-until-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75593650[job-ids-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755935e0[job-k-octets-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755934e0[job-priority-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594670[job-settable-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755947e0[job-sheets-supported,4,80000042])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75593400[jpeg-k-octets-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755933c0[jpeg-x-dimension-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594990[jpeg-y-dimension-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594a00[media-col-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75593fc0[multiple-document-handling-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755948b0[multiple-document-jobs-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755947a0[multiple-operation-time-out,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594600[natural-language-configured,4,48])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583df0[notify-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594590[notify-lease-duration-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594520[notify-max-events-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583770[notify-events-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75594400[notify-pull-method-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f756587e0[notify-schemes-supported,4,44])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f755936c0[number-up-supported,4,21])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75662e60[operations-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75663180[orientation-requested-supported,4,23])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f756631f0[page-ranges-supported,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75663230[pdf-k-octets-supported,4,33])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75583c40[pdf-versions-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75663270[pdl-override-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f756632b0[printer-op-policy-supported,4,80000042])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75663300[printer-settable-attributes-supported,4,80000044])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75663390[server-is-sharing-printers,4,22])
d [14/Mar/2012:16:14:27 +0000] copy_attribute(0x7f0f756cde00, 0x7f0f75584000[which-jobs-supported,4,80000044])
D [14/Mar/2012:16:14:27 +0000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/delly) from localhost
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest: ippLength(response)=7964
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=0x7f0f73a782f0, data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] select_timeout(0): 1 seconds to send browse update
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(0), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756d0b90(16)) response=0x7f0f756cde00(2), file=-1 pipe_pid=0 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d0b90)
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [14/Mar/2012:16:14:27 +0000] [CGI] Getting list of devices...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization=""
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: No authentication data provided.
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=83, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=83, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=54, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=54, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=17, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=17, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Devices 1
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest(0x7f0f756d0b90[16]): operation_id = 400b
D [14/Mar/2012:16:14:27 +0000] CUPS-Get-Devices
d [14/Mar/2012:16:14:27 +0000] get_devices(0x7f0f756d0b90[16])
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=400b(CUPS-Get-Devices))
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f75573f20((null))
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Negotiate, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: op=400b(CUPS-Get-Devices)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: username=""
D [14/Mar/2012:16:14:27 +0000] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
d [14/Mar/2012:16:14:27 +0000] cupsdSendError(con=0x7f0f756d0b90(16), code=401, auth_type=0
D [14/Mar/2012:16:14:27 +0000] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS"
d [14/Mar/2012:16:14:27 +0000] select_timeout(0): 1 seconds to send browse update
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 16
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=16)
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=2
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 16 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=3
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 20 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=20, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d7600)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 16
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=16)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 POST / HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization="Local 0279CF8BFC3833A93728E6BE30FE1381"
d [14/Mar/2012:16:14:27 +0000] cupsdFindCert(certificate=0279CF8BFC3833A93728E6BE30FE1381)
d [14/Mar/2012:16:14:27 +0000] cupsdFindCert: Returning sam at EXAMPLE.COM...
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorized as sam at EXAMPLE.COM using Local
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=83, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=83, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=54, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=54, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=17, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=17, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 1.1 CUPS-Get-Devices 1
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest(0x7f0f756d7600[20]): operation_id = 400b
D [14/Mar/2012:16:14:27 +0000] CUPS-Get-Devices
d [14/Mar/2012:16:14:27 +0000] get_devices(0x7f0f756d7600[20])
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=400b(CUPS-Get-Devices))
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f75573f20((null))
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Negotiate, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: op=400b(CUPS-Get-Devices)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: username="sam at EXAMPLE.COM"
E [14/Mar/2012:16:14:27 +0000] Authorized using Basic, expected Negotiate!
D [14/Mar/2012:16:14:27 +0000] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
d [14/Mar/2012:16:14:27 +0000] cupsdSendError(con=0x7f0f756d7600(20), code=401, auth_type=0
D [14/Mar/2012:16:14:27 +0000] cupsdSendHeader: 20 WWW-Authenticate: Basic realm="CUPS"
d [14/Mar/2012:16:14:27 +0000] select_timeout(0): 1 seconds to send browse update
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 20
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=20)
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=2
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 16 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=3
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 20 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=20, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d7600)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 16
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=16)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 POST / HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization="Local 0279CF8BFC3833A93728E6BE30FE1381"
d [14/Mar/2012:16:14:27 +0000] cupsdFindCert(certificate=0279CF8BFC3833A93728E6BE30FE1381)
d [14/Mar/2012:16:14:27 +0000] cupsdFindCert: Returning sam at EXAMPLE.COM...
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorized as sam at EXAMPLE.COM using Local
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=83, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=83, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=54, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=54, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=17, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=17, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 1.1 CUPS-Get-Devices 1
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest(0x7f0f756d7600[20]): operation_id = 400b
D [14/Mar/2012:16:14:27 +0000] CUPS-Get-Devices
d [14/Mar/2012:16:14:27 +0000] get_devices(0x7f0f756d7600[20])
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=400b(CUPS-Get-Devices))
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f75573f20((null))
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Negotiate, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: op=400b(CUPS-Get-Devices)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: username="sam at EXAMPLE.COM"
E [14/Mar/2012:16:14:27 +0000] Authorized using Basic, expected Negotiate!
D [14/Mar/2012:16:14:27 +0000] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
d [14/Mar/2012:16:14:27 +0000] cupsdSendError(con=0x7f0f756d7600(20), code=401, auth_type=0
D [14/Mar/2012:16:14:27 +0000] cupsdSendHeader: 20 WWW-Authenticate: Basic realm="CUPS"
d [14/Mar/2012:16:14:27 +0000] select_timeout(0): 1 seconds to send browse update
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 20
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=20)
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=2
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 16 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=16, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d0b90)
d [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient(lis=0x7f0f75570c30(11)) Clients=3
D [14/Mar/2012:16:14:27 +0000] cupsdAcceptClient: 20 from localhost (Domain)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=20, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756d7600)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d0b90(16)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 16
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=16)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 POST / HTTP/1.1
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: uri = "/"...
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:27 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorization="Local 0279CF8BFC3833A93728E6BE30FE1381"
d [14/Mar/2012:16:14:27 +0000] cupsdFindCert(certificate=0279CF8BFC3833A93728E6BE30FE1381)
d [14/Mar/2012:16:14:27 +0000] cupsdFindCert: Returning sam at EXAMPLE.COM...
D [14/Mar/2012:16:14:27 +0000] cupsdAuthorize: Authorized as sam at EXAMPLE.COM using Local
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f755712a0(/)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=83, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=83, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=54, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=54, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=17, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=17, con->file=-1
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 1.1 CUPS-Get-Devices 1
d [14/Mar/2012:16:14:27 +0000] cupsdProcessIPPRequest(0x7f0f756d7600[20]): operation_id = 400b
D [14/Mar/2012:16:14:27 +0000] CUPS-Get-Devices
d [14/Mar/2012:16:14:27 +0000] get_devices(0x7f0f756d7600[20])
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=400b(CUPS-Get-Devices))
d [14/Mar/2012:16:14:27 +0000] cupsdFindPolicyOp: Found exact match...
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: con->uri="/", con->best=0x7f0f75573f20((null))
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Negotiate, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: op=400b(CUPS-Get-Devices)
d [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [14/Mar/2012:16:14:27 +0000] cupsdIsAuthorized: username="sam at EXAMPLE.COM"
E [14/Mar/2012:16:14:27 +0000] Authorized using Basic, expected Negotiate!
D [14/Mar/2012:16:14:27 +0000] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
d [14/Mar/2012:16:14:27 +0000] cupsdSendError(con=0x7f0f756d7600(20), code=401, auth_type=0
D [14/Mar/2012:16:14:27 +0000] cupsdSendHeader: 20 WWW-Authenticate: Basic realm="CUPS"
d [14/Mar/2012:16:14:27 +0000] select_timeout(0): 1 seconds to send browse update
D [14/Mar/2012:16:14:27 +0000] [CGI] cgi_passwd(prompt="Password for lp on localhost? ") called!
d [14/Mar/2012:16:14:27 +0000] write_pipe(con=0x7f0f756dc220(17)) CGI output on fd 19
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=19)
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=17, read_cb=(nil), write_cb=0x7f0f73a782f0, data=0x7f0f756dc220)
d [14/Mar/2012:16:14:27 +0000] cupsdWriteClient(con=0x7f0f756dc220(17)) response=(nil)(-1), file=19 pipe_pid=59573 state=7
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=19, read_cb=0x7f0f73a75a30, write_cb=(nil), data=0x7f0f756dc220)
D [14/Mar/2012:16:14:27 +0000] Script header: Status: 401
d [14/Mar/2012:16:14:27 +0000] cupsdSendError(con=0x7f0f756dc220(17), code=401, auth_type=0
D [14/Mar/2012:16:14:27 +0000] cupsdSendHeader: 17 WWW-Authenticate: Negotiate
D [14/Mar/2012:16:14:27 +0000] Script header:
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=17, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756dc220)
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=19)
d [14/Mar/2012:16:14:27 +0000] cupsdEndProcess(pid=59573, force=0)
D [14/Mar/2012:16:14:27 +0000] cupsdWriteClient: 17 Closing because Keep-Alive disabled
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 17
D [14/Mar/2012:16:14:27 +0000] SSL shutdown successful!
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdAddSelect(fd=17, read_cb=0x7f0f73a78940, write_cb=(nil), data=0x7f0f756dc220)
d [14/Mar/2012:16:14:27 +0000] process_children()
d [14/Mar/2012:16:14:27 +0000] cupsdFinishProcess(pid=59573, name=0x7fffb7a66710, namelen=1024, job_id=0x7fffb7a6670c(0)) = "/usr/lib/cups/cgi-bin/admin.cgi"
d [14/Mar/2012:16:14:27 +0000] cupsdDeleteCert: Removing certificate for PID 59573
D [14/Mar/2012:16:14:27 +0000] PID 59573 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756dc220(17)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_CHUNKED, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 17 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 17
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=17)
d [14/Mar/2012:16:14:27 +0000] cupsdReadClient(con=0x7f0f756d7600(20)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
D [14/Mar/2012:16:14:27 +0000] cupsdReadClient: 20 WAITING Closing on EOF
D [14/Mar/2012:16:14:27 +0000] cupsdCloseClient: 20
D [14/Mar/2012:16:14:27 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [14/Mar/2012:16:14:27 +0000] cupsdRemoveSelect(fd=20)
d [14/Mar/2012:16:14:28 +0000] cupsdFindBest: uri = "/printers/delly"...
d [14/Mar/2012:16:14:28 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:28 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:28 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:28 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:28 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:28 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=2(Print-Job))
d [14/Mar/2012:16:14:28 +0000] cupsdFindPolicyOp: Found wildcard match...
d [14/Mar/2012:16:14:28 +0000] cupsdSendBrowseList: (206 bytes to "eth0") 8090de 3 ipp://10.0.0.1:631/printers/delly "" "Monochrome laser printer with duplexer" "Dell 2330dn Laser Printer" job-sheets=none,none lease-duration=300 uuid=urn:uuid:5fa3ba98-ab8f-3522-77b2-e2059514b314
d [14/Mar/2012:16:14:28 +0000] cupsdSendBrowseList: (211 bytes to "eth1") 8090de 3 ipp://81.110.190.38:631/printers/delly "" "Monochrome laser printer with duplexer" "Dell 2330dn Laser Printer" job-sheets=none,none lease-duration=300 uuid=urn:uuid:5fa3ba98-ab8f-3522-77b2-e2059514b314
d [14/Mar/2012:16:14:28 +0000] select_timeout(0): 1 seconds to send browse update
d [14/Mar/2012:16:14:29 +0000] cupsdFindBest: uri = "/printers/officejet_4200"...
d [14/Mar/2012:16:14:29 +0000] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [14/Mar/2012:16:14:29 +0000] cupsdFindBest: Location /admin/conf Limit 7f
d [14/Mar/2012:16:14:29 +0000] cupsdFindBest: Location /admin Limit 7f
d [14/Mar/2012:16:14:29 +0000] cupsdFindBest: Location / Limit 7f
d [14/Mar/2012:16:14:29 +0000] cupsdFindBest: best = /
d [14/Mar/2012:16:14:29 +0000] cupsdFindPolicyOp(p=0x7f0f75571b40, op=2(Print-Job))
d [14/Mar/2012:16:14:29 +0000] cupsdFindPolicyOp: Found wildcard match...
d [14/Mar/2012:16:14:29 +0000] cupsdSendBrowseList: (202 bytes to "eth0") 900e 3 ipp://10.0.0.1:631/printers/officejet_4200 "" "Colour inkjet" "HP Officejet 4200 Series, hpcups 3.10.6" job-sheets=none,none lease-duration=300 uuid=urn:uuid:c222a6ed-ad70-3b28-725e-f73012ab9776
d [14/Mar/2012:16:14:29 +0000] cupsdSendBrowseList: (207 bytes to "eth1") 900e 3 ipp://81.110.190.38:631/printers/officejet_4200 "" "Colour inkjet" "HP Officejet 4200 Series, hpcups 3.10.6" job-sheets=none,none lease-duration=300 uuid=urn:uuid:c222a6ed-ad70-3b28-725e-f73012ab9776
d [14/Mar/2012:16:14:29 +0000] select_timeout(0): 30 seconds to send browse update
More information about the Pkg-cups-devel
mailing list