Authentication to CUPS from kprinter

Tue Sep 12 10:33:33 CEST 2006

Goffioul Michael wrote:
>> The error log shows the following: (I put a larger part in the mail)
>>     
>
> Could you turn on LogLevel to debug2 in the CUPS server, restart it and
> provide the same log information?
>
> Michael.
> _______________________________________________
> kde-print mailing list
> kde-print at kde.org
>   
> https://mail.kde.org/mailman/listinfo/kde-print
>   
Hi, quite a lot ouput.

I started cancelling on 10:23:00 and put the log file until the shutdown
of the server into the mail.

Thanks for your fast response.

Greetings, Helge

d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x57e290[job-originating-user-name,2,42])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x57e2d0[job-name,2,42])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x57e3d0[job-priority,2,21])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x57e5b0[job-id,2,21])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x57e5f0[job-state,2,23])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x581120[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x581160[job-printer-uri,2,45])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x5811a0[job-name,2,42])
d [12/Sep/2006:10:22:58 +0200] copy_attribute(0x590830,
0x5811e0[job-k-octets,2,21])
D [12/Sep/2006:10:22:58 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:22:58 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:22:58 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:22:58 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:22:58 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:22:58 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:22:58 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:22:58 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:22:59 +0200] select_timeout: 11 seconds to process
active jobs
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdCheckJobs: 2 active jobs,
sleeping=0, reload=0
d [12/Sep/2006:10:23:03 +0200] cupsdCheckJobs: Job 16: state_value=3,
loaded=yes
d [12/Sep/2006:10:23:03 +0200] cupsdCheckJobs: Job 17: state_value=3,
loaded=yes
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:03 +0200] POST /printers/
d [12/Sep/2006:10:23:03 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4002
D [12/Sep/2006:10:23:03 +0200] CUPS-Get-Printers
d [12/Sep/2006:10:23:03 +0200] get_printers(0x58cf50[8], 0)
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4002(CUPS-Get-Printers))
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:03 +0200]
printer-uri-supported="ipp://lxgmm06.lrz-muenchen.de:631/printers/test"
d [12/Sep/2006:10:23:03 +0200] copy_attrs(to=0x5907f0, from=0x578cc0,
ra=0x590a30, group=0, quickcopy=0)
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x5907f0,
0x578e50[printer-name,4,42])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x5907f0,
0x578ed0[printer-location,4,41])
d [12/Sep/2006:10:23:03 +0200] copy_attrs(to=0x5907f0, from=0x565080,
ra=0x590a30, group=0, quickcopy=-2147483648)
D [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8 POST /classes/ HTTP/1.1
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: uri = "/classes/"...
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:03 +0200] POST /classes/
d [12/Sep/2006:10:23:03 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4005
D [12/Sep/2006:10:23:03 +0200] CUPS-Get-Classes
d [12/Sep/2006:10:23:03 +0200] get_printers(0x58cf50[8], 1)
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4005(CUPS-Get-Classes))
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
D [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:03 +0200] POST /printers/
d [12/Sep/2006:10:23:03 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=109, con->file=-1
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4001
D [12/Sep/2006:10:23:03 +0200] CUPS-Get-Default
d [12/Sep/2006:10:23:03 +0200] get_default(0x58cf50[8])
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4001(CUPS-Get-Default))
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
E [12/Sep/2006:10:23:03 +0200] CUPS-Get-Default client-error-not-found:
No default printer
D [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: 8 status_code=406
(client-error-not-found)
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:03 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8 POST / HTTP/1.1
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: uri = "/"...
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:03 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:03 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:03 +0200] POST /
d [12/Sep/2006:10:23:03 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=384, con->file=-1
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 000a
D [12/Sep/2006:10:23:03 +0200] Get-Jobs
ipp://lxgmm06.lrz-muenchen.de:631/printers/test
d [12/Sep/2006:10:23:03 +0200] get_jobs(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/printers/test)
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp(p=0x56a530, op=a(Get-Jobs))
d [12/Sep/2006:10:23:03 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:03 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:03 +0200] get_jobs: job->id = 16
d [12/Sep/2006:10:23:03 +0200] get_jobs: count = 1
d [12/Sep/2006:10:23:03 +0200] copy_attrs(to=0x590830, from=0x57c100,
ra=0x590b60, group=2, quickcopy=0)
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57d480[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57d510[job-name,2,42])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57d760[job-priority,2,21])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57db10[job-id,2,21])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57db90[job-state,2,23])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57dc10[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57dca0[job-printer-uri,2,45])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57dd20[job-name,2,42])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57dd60[job-k-octets,2,21])
d [12/Sep/2006:10:23:03 +0200] get_jobs: job->id = 17
d [12/Sep/2006:10:23:03 +0200] get_jobs: count = 2
d [12/Sep/2006:10:23:03 +0200] copy_attrs(to=0x590830, from=0x57de60,
ra=0x590b60, group=2, quickcopy=0)
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57e290[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57e2d0[job-name,2,42])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57e3d0[job-priority,2,21])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57e5b0[job-id,2,21])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x57e5f0[job-state,2,23])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x581120[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x581160[job-printer-uri,2,45])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x5811a0[job-name,2,42])
d [12/Sep/2006:10:23:03 +0200] copy_attribute(0x590830,
0x5811e0[job-k-octets,2,21])
D [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:03 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:03 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:03 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:03 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:04 +0200] select_timeout: 11 seconds to process
active jobs
d [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8 POST /jobs/ HTTP/1.1
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: uri = "/jobs/"...
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:06 +0200] POST /jobs/
d [12/Sep/2006:10:23:06 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=157, con->file=-1
d [12/Sep/2006:10:23:06 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 0008
D [12/Sep/2006:10:23:06 +0200] Cancel-Job
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17
d [12/Sep/2006:10:23:06 +0200] cancel_job(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17)
d [12/Sep/2006:10:23:06 +0200] validate_user(job=17, con=8,
owner="a2825ai", username=0x7fbfff10b0, userlen=1024)
d [12/Sep/2006:10:23:06 +0200] cupsdFindPolicyOp(p=0x56a530,
op=8(Cancel-Job))
d [12/Sep/2006:10:23:06 +0200] cupsdFindPolicyOp: Found exact match...
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56bfb0((null))
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: owner="a2825ai"
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: level=AUTH_USER,
type=AUTH_BASIC, satisfy=AUTH_SATISFY_ALL, num_names=2
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: op=8(Cancel-Job)
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: username=""
E [12/Sep/2006:10:23:06 +0200] Cancel-Job: Unauthorized
d [12/Sep/2006:10:23:06 +0200] cupsdSendError: Encryption before
authentication!
D [12/Sep/2006:10:23:06 +0200] cupsdSendError: 8 code=426 (Upgrade Required)
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:06 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:06 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:06 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8 OPTIONS * HTTP/1.1
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: uri = "*"...
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: best = NONE
d [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: con->uri="*",
con->best=(nil)()
d [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: No authentication data
provided.
D [12/Sep/2006:10:23:06 +0200] encrypt_client: 8 Connection from
129.187.15.9 now encrypted.
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8 POST /jobs/ HTTP/1.1
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: uri = "/jobs/"...
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:06 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:06 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:06 +0200] POST /jobs/
d [12/Sep/2006:10:23:06 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=157, con->file=-1
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=149, con->file=-1
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=120, con->file=-1
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=86, con->file=-1
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=33, con->file=-1
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
d [12/Sep/2006:10:23:06 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 0008
D [12/Sep/2006:10:23:06 +0200] Cancel-Job
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17
d [12/Sep/2006:10:23:06 +0200] cancel_job(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17)
d [12/Sep/2006:10:23:06 +0200] validate_user(job=17, con=8,
owner="a2825ai", username=0x7fbfff10b0, userlen=1024)
d [12/Sep/2006:10:23:06 +0200] cupsdFindPolicyOp(p=0x56a530,
op=8(Cancel-Job))
d [12/Sep/2006:10:23:06 +0200] cupsdFindPolicyOp: Found exact match...
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56bfb0((null))
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: owner="a2825ai"
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: level=AUTH_USER,
type=AUTH_BASIC, satisfy=AUTH_SATISFY_ALL, num_names=2
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: op=8(Cancel-Job)
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:06 +0200] cupsdIsAuthorized: username=""
E [12/Sep/2006:10:23:06 +0200] Cancel-Job: Unauthorized
D [12/Sep/2006:10:23:06 +0200] cupsdSendError: 8 code=401 (Unauthorized)
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:06 +0200] cupsdCloseClient: 8
I [12/Sep/2006:10:23:06 +0200] cupsdCloseClient: SSL shutdown successful!
d [12/Sep/2006:10:23:06 +0200] cupsdCloseClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:06 +0200] cupsdReadClient: http error seen...
D [12/Sep/2006:10:23:06 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:06 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:07 +0200] select_timeout: 11 seconds to process
active jobs
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:09 +0200] POST /printers/
d [12/Sep/2006:10:23:09 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4002
D [12/Sep/2006:10:23:09 +0200] CUPS-Get-Printers
d [12/Sep/2006:10:23:09 +0200] get_printers(0x58cf50[8], 0)
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4002(CUPS-Get-Printers))
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:09 +0200]
printer-uri-supported="ipp://lxgmm06.lrz-muenchen.de:631/printers/test"
d [12/Sep/2006:10:23:09 +0200] copy_attrs(to=0x594c20, from=0x578cc0,
ra=0x592e20, group=0, quickcopy=0)
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c20,
0x578e50[printer-name,4,42])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c20,
0x578ed0[printer-location,4,41])
d [12/Sep/2006:10:23:09 +0200] copy_attrs(to=0x594c20, from=0x565080,
ra=0x592e20, group=0, quickcopy=-2147483648)
D [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8 POST /classes/ HTTP/1.1
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: uri = "/classes/"...
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:09 +0200] POST /classes/
d [12/Sep/2006:10:23:09 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4005
D [12/Sep/2006:10:23:09 +0200] CUPS-Get-Classes
d [12/Sep/2006:10:23:09 +0200] get_printers(0x58cf50[8], 1)
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4005(CUPS-Get-Classes))
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
D [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:09 +0200] POST /printers/
d [12/Sep/2006:10:23:09 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=109, con->file=-1
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4001
D [12/Sep/2006:10:23:09 +0200] CUPS-Get-Default
d [12/Sep/2006:10:23:09 +0200] get_default(0x58cf50[8])
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4001(CUPS-Get-Default))
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
E [12/Sep/2006:10:23:09 +0200] CUPS-Get-Default client-error-not-found:
No default printer
D [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: 8 status_code=406
(client-error-not-found)
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:09 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8 POST / HTTP/1.1
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: uri = "/"...
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:09 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:09 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:09 +0200] POST /
d [12/Sep/2006:10:23:09 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=384, con->file=-1
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 000a
D [12/Sep/2006:10:23:09 +0200] Get-Jobs
ipp://lxgmm06.lrz-muenchen.de:631/printers/test
d [12/Sep/2006:10:23:09 +0200] get_jobs(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/printers/test)
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp(p=0x56a530, op=a(Get-Jobs))
d [12/Sep/2006:10:23:09 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:09 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:09 +0200] get_jobs: job->id = 16
d [12/Sep/2006:10:23:09 +0200] get_jobs: count = 1
d [12/Sep/2006:10:23:09 +0200] copy_attrs(to=0x594c60, from=0x57c100,
ra=0x592de0, group=2, quickcopy=0)
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57d480[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57d510[job-name,2,42])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57d760[job-priority,2,21])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57db10[job-id,2,21])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57db90[job-state,2,23])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57dc10[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57dca0[job-printer-uri,2,45])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57dd20[job-name,2,42])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57dd60[job-k-octets,2,21])
d [12/Sep/2006:10:23:09 +0200] get_jobs: job->id = 17
d [12/Sep/2006:10:23:09 +0200] get_jobs: count = 2
d [12/Sep/2006:10:23:09 +0200] copy_attrs(to=0x594c60, from=0x57de60,
ra=0x592de0, group=2, quickcopy=0)
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57e290[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57e2d0[job-name,2,42])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57e3d0[job-priority,2,21])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57e5b0[job-id,2,21])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x57e5f0[job-state,2,23])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x581120[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x581160[job-printer-uri,2,45])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x5811a0[job-name,2,42])
d [12/Sep/2006:10:23:09 +0200] copy_attribute(0x594c60,
0x5811e0[job-k-octets,2,21])
D [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:09 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:09 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:09 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:09 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:10 +0200] select_timeout: 11 seconds to process
active jobs
d [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:11 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:11 +0200] cupsdReadClient: 8 POST /jobs/ HTTP/1.1
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: uri = "/jobs/"...
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:11 +0200] cupsdAuthorize: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:11 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:11 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:11 +0200] POST /jobs/
d [12/Sep/2006:10:23:11 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:11 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=157, con->file=-1
d [12/Sep/2006:10:23:11 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 0008
D [12/Sep/2006:10:23:11 +0200] Cancel-Job
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17
d [12/Sep/2006:10:23:11 +0200] cancel_job(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17)
d [12/Sep/2006:10:23:11 +0200] validate_user(job=17, con=8,
owner="a2825ai", username=0x7fbfff10b0, userlen=1024)
d [12/Sep/2006:10:23:11 +0200] cupsdFindPolicyOp(p=0x56a530,
op=8(Cancel-Job))
d [12/Sep/2006:10:23:11 +0200] cupsdFindPolicyOp: Found exact match...
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56bfb0((null))
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: owner="a2825ai"
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: level=AUTH_USER,
type=AUTH_BASIC, satisfy=AUTH_SATISFY_ALL, num_names=2
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: op=8(Cancel-Job)
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:11 +0200] cupsdIsAuthorized: username=""
E [12/Sep/2006:10:23:11 +0200] Cancel-Job: Unauthorized
d [12/Sep/2006:10:23:11 +0200] cupsdSendError: Encryption before
authentication!
D [12/Sep/2006:10:23:11 +0200] cupsdSendError: 8 code=426 (Upgrade Required)
d [12/Sep/2006:10:23:11 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:11 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:11 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:11 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:11 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:11 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:11 +0200] cupsdReadClient: 8 OPTIONS * HTTP/1.1
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: uri = "*"...
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:11 +0200] cupsdFindBest: best = NONE
d [12/Sep/2006:10:23:11 +0200] cupsdAuthorize: con->uri="*",
con->best=(nil)()
d [12/Sep/2006:10:23:11 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:11 +0200] cupsdAuthorize: No authentication data
provided.
D [12/Sep/2006:10:23:12 +0200] encrypt_client: 8 Connection from
129.187.15.9 now encrypted.
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8 POST /jobs/ HTTP/1.1
d [12/Sep/2006:10:23:12 +0200] cupsdFindBest: uri = "/jobs/"...
d [12/Sep/2006:10:23:12 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:12 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:12 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:12 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:12 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:12 +0200] cupsdAuthorize: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:12 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:12 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:12 +0200] POST /jobs/
d [12/Sep/2006:10:23:12 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=157, con->file=-1
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=149, con->file=-1
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=120, con->file=-1
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=86, con->file=-1
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=33, con->file=-1
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=6
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
d [12/Sep/2006:10:23:12 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 0008
D [12/Sep/2006:10:23:12 +0200] Cancel-Job
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17
d [12/Sep/2006:10:23:12 +0200] cancel_job(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/jobs/17)
d [12/Sep/2006:10:23:12 +0200] validate_user(job=17, con=8,
owner="a2825ai", username=0x7fbfff10b0, userlen=1024)
d [12/Sep/2006:10:23:12 +0200] cupsdFindPolicyOp(p=0x56a530,
op=8(Cancel-Job))
d [12/Sep/2006:10:23:12 +0200] cupsdFindPolicyOp: Found exact match...
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: con->uri="/jobs/",
con->best=0x56bfb0((null))
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: owner="a2825ai"
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: level=AUTH_USER,
type=AUTH_BASIC, satisfy=AUTH_SATISFY_ALL, num_names=2
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: op=8(Cancel-Job)
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:12 +0200] cupsdIsAuthorized: username=""
E [12/Sep/2006:10:23:12 +0200] Cancel-Job: Unauthorized
D [12/Sep/2006:10:23:12 +0200] cupsdSendError: 8 code=401 (Unauthorized)
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:12 +0200] cupsdCloseClient: 8
I [12/Sep/2006:10:23:12 +0200] cupsdCloseClient: SSL shutdown successful!
d [12/Sep/2006:10:23:12 +0200] cupsdCloseClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:12 +0200] cupsdReadClient: http error seen...
D [12/Sep/2006:10:23:12 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:12 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdCheckJobs: 2 active jobs,
sleeping=0, reload=0
d [12/Sep/2006:10:23:13 +0200] cupsdCheckJobs: Job 16: state_value=3,
loaded=yes
d [12/Sep/2006:10:23:13 +0200] cupsdCheckJobs: Job 17: state_value=3,
loaded=yes
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:13 +0200] POST /printers/
d [12/Sep/2006:10:23:13 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4002
D [12/Sep/2006:10:23:13 +0200] CUPS-Get-Printers
d [12/Sep/2006:10:23:13 +0200] get_printers(0x58cf50[8], 0)
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4002(CUPS-Get-Printers))
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:13 +0200]
printer-uri-supported="ipp://lxgmm06.lrz-muenchen.de:631/printers/test"
d [12/Sep/2006:10:23:13 +0200] copy_attrs(to=0x594c20, from=0x578cc0,
ra=0x592e20, group=0, quickcopy=0)
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c20,
0x578e50[printer-name,4,42])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c20,
0x578ed0[printer-location,4,41])
d [12/Sep/2006:10:23:13 +0200] copy_attrs(to=0x594c20, from=0x565080,
ra=0x592e20, group=0, quickcopy=-2147483648)
D [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8 POST /classes/ HTTP/1.1
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: uri = "/classes/"...
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:13 +0200] POST /classes/
d [12/Sep/2006:10:23:13 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4005
D [12/Sep/2006:10:23:13 +0200] CUPS-Get-Classes
d [12/Sep/2006:10:23:13 +0200] get_printers(0x58cf50[8], 1)
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4005(CUPS-Get-Classes))
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
D [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:13 +0200] POST /printers/
d [12/Sep/2006:10:23:13 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=109, con->file=-1
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4001
D [12/Sep/2006:10:23:13 +0200] CUPS-Get-Default
d [12/Sep/2006:10:23:13 +0200] get_default(0x58cf50[8])
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4001(CUPS-Get-Default))
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
E [12/Sep/2006:10:23:13 +0200] CUPS-Get-Default client-error-not-found:
No default printer
D [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: 8 status_code=406
(client-error-not-found)
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:13 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8 POST / HTTP/1.1
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: uri = "/"...
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:13 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:13 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:13 +0200] POST /
d [12/Sep/2006:10:23:13 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=384, con->file=-1
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 000a
D [12/Sep/2006:10:23:13 +0200] Get-Jobs
ipp://lxgmm06.lrz-muenchen.de:631/printers/test
d [12/Sep/2006:10:23:13 +0200] get_jobs(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/printers/test)
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp(p=0x56a530, op=a(Get-Jobs))
d [12/Sep/2006:10:23:13 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:13 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:13 +0200] get_jobs: job->id = 16
d [12/Sep/2006:10:23:13 +0200] get_jobs: count = 1
d [12/Sep/2006:10:23:13 +0200] copy_attrs(to=0x594c60, from=0x57c100,
ra=0x592de0, group=2, quickcopy=0)
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57d480[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57d510[job-name,2,42])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57d760[job-priority,2,21])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57db10[job-id,2,21])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57db90[job-state,2,23])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57dc10[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57dca0[job-printer-uri,2,45])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57dd20[job-name,2,42])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57dd60[job-k-octets,2,21])
d [12/Sep/2006:10:23:13 +0200] get_jobs: job->id = 17
d [12/Sep/2006:10:23:13 +0200] get_jobs: count = 2
d [12/Sep/2006:10:23:13 +0200] copy_attrs(to=0x594c60, from=0x57de60,
ra=0x592de0, group=2, quickcopy=0)
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57e290[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57e2d0[job-name,2,42])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57e3d0[job-priority,2,21])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57e5b0[job-id,2,21])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x57e5f0[job-state,2,23])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x581120[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x581160[job-printer-uri,2,45])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x5811a0[job-name,2,42])
d [12/Sep/2006:10:23:13 +0200] copy_attribute(0x594c60,
0x5811e0[job-k-octets,2,21])
D [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:13 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:13 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:13 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:13 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:14 +0200] select_timeout: 10 seconds to send browse
update
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:18 +0200] POST /printers/
d [12/Sep/2006:10:23:18 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4002
D [12/Sep/2006:10:23:18 +0200] CUPS-Get-Printers
d [12/Sep/2006:10:23:18 +0200] get_printers(0x58cf50[8], 0)
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4002(CUPS-Get-Printers))
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:18 +0200]
printer-uri-supported="ipp://lxgmm06.lrz-muenchen.de:631/printers/test"
d [12/Sep/2006:10:23:18 +0200] copy_attrs(to=0x594c20, from=0x578cc0,
ra=0x592e20, group=0, quickcopy=0)
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c20,
0x578e50[printer-name,4,42])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c20,
0x578ed0[printer-location,4,41])
d [12/Sep/2006:10:23:18 +0200] copy_attrs(to=0x594c20, from=0x565080,
ra=0x592e20, group=0, quickcopy=-2147483648)
D [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8 POST /classes/ HTTP/1.1
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: uri = "/classes/"...
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:18 +0200] POST /classes/
d [12/Sep/2006:10:23:18 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=221, con->file=-1
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4005
D [12/Sep/2006:10:23:18 +0200] CUPS-Get-Classes
d [12/Sep/2006:10:23:18 +0200] get_printers(0x58cf50[8], 1)
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4005(CUPS-Get-Classes))
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/classes/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
D [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8 POST /printers/ HTTP/1.1
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: uri = "/printers/"...
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:18 +0200] POST /printers/
d [12/Sep/2006:10:23:18 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=109, con->file=-1
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 4001
D [12/Sep/2006:10:23:18 +0200] CUPS-Get-Default
d [12/Sep/2006:10:23:18 +0200] get_default(0x58cf50[8])
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp(p=0x56a530,
op=4001(CUPS-Get-Default))
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/printers/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
E [12/Sep/2006:10:23:18 +0200] CUPS-Get-Default client-error-not-found:
No default printer
D [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: 8 status_code=406
(client-error-not-found)
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient(lis=0x569dc0) 2 Clients = 0
D [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 from
129.187.15.9:631 (IPv4)
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: 8 connected to server
on lxgmm06.lrz-muenchen.de:631
d [12/Sep/2006:10:23:18 +0200] cupsdAcceptClient: Adding fd 8 to InputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
D [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8 POST / HTTP/1.1
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: uri = "/"...
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location
CUPS_INTERNAL_BROWSE_ACL Limit 0
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin/conf Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location /admin Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: Location / Limit 7f
d [12/Sep/2006:10:23:18 +0200] cupsdFindBest: best = /
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: Authorization=""
D [12/Sep/2006:10:23:18 +0200] cupsdAuthorize: No authentication data
provided.
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56a170(/)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:18 +0200] POST /
d [12/Sep/2006:10:23:18 +0200] CONTENT_TYPE = application/ipp
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8
con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=384, con->file=-1
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest(0x58cf50[8]):
operation_id = 000a
D [12/Sep/2006:10:23:18 +0200] Get-Jobs
ipp://lxgmm06.lrz-muenchen.de:631/printers/test
d [12/Sep/2006:10:23:18 +0200] get_jobs(0x58cf50[8],
ipp://lxgmm06.lrz-muenchen.de:631/printers/test)
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp(p=0x56a530, op=a(Get-Jobs))
d [12/Sep/2006:10:23:18 +0200] cupsdFindPolicyOp: Found wildcard match...
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: con->uri="/",
con->best=0x56c120((null))
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: level=AUTH_ANON,
type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: op=0(unknown-0000)
d [12/Sep/2006:10:23:18 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW...
d [12/Sep/2006:10:23:18 +0200] get_jobs: job->id = 16
d [12/Sep/2006:10:23:18 +0200] get_jobs: count = 1
d [12/Sep/2006:10:23:18 +0200] copy_attrs(to=0x594c60, from=0x57c100,
ra=0x592de0, group=2, quickcopy=0)
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57d480[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57d510[job-name,2,42])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57d760[job-priority,2,21])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57db10[job-id,2,21])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57db90[job-state,2,23])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57dc10[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57dca0[job-printer-uri,2,45])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57dd20[job-name,2,42])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57dd60[job-k-octets,2,21])
d [12/Sep/2006:10:23:18 +0200] get_jobs: job->id = 17
d [12/Sep/2006:10:23:18 +0200] get_jobs: count = 2
d [12/Sep/2006:10:23:18 +0200] copy_attrs(to=0x594c60, from=0x57de60,
ra=0x592de0, group=2, quickcopy=0)
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57e290[job-originating-user-name,2,42])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57e2d0[job-name,2,42])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57e3d0[job-priority,2,21])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57e5b0[job-id,2,21])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x57e5f0[job-state,2,23])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x581120[job-media-sheets-completed,2,21])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x581160[job-printer-uri,2,45])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x5811a0[job-name,2,42])
d [12/Sep/2006:10:23:18 +0200] copy_attribute(0x594c60,
0x5811e0[job-k-octets,2,21])
D [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: 8 status_code=0
(successful-ok)
d [12/Sep/2006:10:23:18 +0200] cupsdProcessIPPRequest: Adding fd 8 to
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: 8 bytes < 0
d [12/Sep/2006:10:23:18 +0200] cupsdWriteClient: Removing fd 8 from
OutputSet...
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: 8, used=0, file=-1 state=0
d [12/Sep/2006:10:23:18 +0200] cupsdReadClient: httpGets returned EOF...
D [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: 8
d [12/Sep/2006:10:23:18 +0200] cupsdCloseClient: Removing fd 8 from
InputSet and OutputSet...
d [12/Sep/2006:10:23:19 +0200] select_timeout: 5 seconds to send browse
update
I [12/Sep/2006:10:23:21 +0200] Scheduler shutting down normally.
d [12/Sep/2006:10:23:21 +0200] cupsdStopListening: closing all listen
sockets.
d [12/Sep/2006:10:23:21 +0200] cupsdPauseListening: Clearing input bits...
d [12/Sep/2006:10:23:21 +0200] cupsdPauseListening: Removing fd 0 from
InputSet...
d [12/Sep/2006:10:23:21 +0200] cupsdPauseListening: Removing fd 2 from
InputSet...
d [12/Sep/2006:10:23:21 +0200] cupsdPauseListening: Removing fd 3 from
InputSet...
d [12/Sep/2006:10:23:21 +0200] cupsdPauseListening: Removing fd 4 from
InputSet...
d [12/Sep/2006:10:23:21 +0200] cupsdStopBrowsing: Removing fd 5 from
InputSet...
I [12/Sep/2006:10:23:21 +0200] Saving remote.cache...
d [12/Sep/2006:10:23:21 +0200] cupsdStopServer: Removing fd 6 from
InputSet...
I [12/Sep/2006:10:23:21 +0200] Saving job cache file
"/usr/local/cups//var/cache/cups/job.cache"...

-- 
Helge Hansemann
Leibniz Rechenzentrum

Boltzmannstrasse 1
85748 Garching
Tel. 089 35831 8789