kprinter doesn't work together with CUPS

[Markus Kitzler]

> Just for the record. Does the problem still exist for a brand new
> created user? (I have little hope, but you never know...)

I'll try that ASAP.

> My impression is that it's not related to CUPS as such, but, for some
> reasons beyond my understanding, that KDEPrint doesn't use the CUPS
> plugin while it tells you it uses it. Here, the KDEPrint debug output
> would *really* help, because it tells you what is happening behind the
> scene (for example how cupsdoprint is called).

That's what I get in /var/log/cups/error_log when I print to a ps-file with 
kprinter:

d [31/Jan/2005:17:32:25 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:17:32:25 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:17:32:25 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:25 +0100] CloseClient() 7
d [31/Jan/2005:17:32:25 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:25 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:17:32:25 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:17:32:25 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:17:32:25 +0100] ReadClient() 7 POST /printers/ HTTP/1.1
d [31/Jan/2005:17:32:25 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:25 +0100] decode_auth() 7 username=""
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:17:32:25 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:17:32:25 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: best = "/"
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:25 +0100] POST /printers/
d [31/Jan/2005:17:32:25 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 112, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 104, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 75, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 38, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 1, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 4002
d [31/Jan/2005:17:32:25 +0100] get_printers(0x40345008[7], 0)
d [31/Jan/2005:17:32:25 +0100] add_printer_state_reasons(0x40345008[7], 
0x80951a0[leda])
d [31/Jan/2005:17:32:25 +0100] add_queued_job_count(0x40345008[7], 0x80951a0
[leda])
d [31/Jan/2005:17:32:25 +0100] copy_attrs(0x823dc88, 0x80929d0, 0x80a23b0, 0)
d [31/Jan/2005:17:32:25 +0100] copy_attribute(0x823dc88, 0x823f300
[printer-name,4,42])
d [31/Jan/2005:17:32:25 +0100] copy_attrs(0x823dc88, 0x8089610, 0x80a23b0, 0)
D [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: 7 status_code=1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:17:32:25 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:17:32:25 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 1
D [31/Jan/2005:17:32:25 +0100] AcceptClient: 8 from localhost:631.
d [31/Jan/2005:17:32:25 +0100] AcceptClient: Adding fd 8 to InputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:25 +0100] CloseClient() 7
d [31/Jan/2005:17:32:25 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 8, used=0, file=-1
D [31/Jan/2005:17:32:25 +0100] ReadClient() 8 POST /printers/ HTTP/1.1
d [31/Jan/2005:17:32:25 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:25 +0100] decode_auth() 8 username=""
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:17:32:25 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:17:32:25 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: best = "/"
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:25 +0100] POST /printers/
d [31/Jan/2005:17:32:25 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:25 +0100] ReadClient() 8 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest(0x40345008[8]): operation_id 
= 4002
d [31/Jan/2005:17:32:25 +0100] get_printers(0x40345008[8], 0)
d [31/Jan/2005:17:32:25 +0100] add_printer_state_reasons(0x40345008[8], 
0x80951a0[leda])
d [31/Jan/2005:17:32:25 +0100] add_queued_job_count(0x40345008[8], 0x80951a0
[leda])
d [31/Jan/2005:17:32:25 +0100] copy_attrs(0x80a2608, 0x80929d0, 0x80a23b0, 0)
d [31/Jan/2005:17:32:25 +0100] copy_attribute(0x80a2608, 0x8240178
[printer-uri-supported,4,45])
d [31/Jan/2005:17:32:25 +0100] copy_attribute(0x80a2608, 0x823f300
[printer-name,4,42])
d [31/Jan/2005:17:32:25 +0100] copy_attribute(0x80a2608, 0x823f0e8
[printer-location,4,41])
d [31/Jan/2005:17:32:25 +0100] copy_attribute(0x80a2608, 0x80a1e68
[printer-type,4,23])
d [31/Jan/2005:17:32:25 +0100] copy_attrs(0x80a2608, 0x8089610, 0x80a23b0, 0)
D [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: 8 status_code=1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: Adding fd 8 to OutputSet...
d [31/Jan/2005:17:32:25 +0100] WriteClient: Removing fd 8 from OutputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 8, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:25 +0100] CloseClient() 8
d [31/Jan/2005:17:32:25 +0100] CloseClient: Removing fd 8 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:25 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:17:32:25 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:17:32:25 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:17:32:25 +0100] ReadClient() 7 POST /classes/ HTTP/1.1
d [31/Jan/2005:17:32:25 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:25 +0100] decode_auth() 7 username=""
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: con->uri = "/classes/"
d [31/Jan/2005:17:32:25 +0100] FindBest: uri = "/classes/"...
d [31/Jan/2005:17:32:25 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: best = "/classes"
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:25 +0100] POST /classes/
d [31/Jan/2005:17:32:25 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 4005
d [31/Jan/2005:17:32:25 +0100] get_printers(0x40345008[7], 1)
D [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: 7 status_code=1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:17:32:25 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:17:32:25 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 1
D [31/Jan/2005:17:32:25 +0100] AcceptClient: 8 from localhost:631.
d [31/Jan/2005:17:32:25 +0100] AcceptClient: Adding fd 8 to InputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:25 +0100] CloseClient() 7
d [31/Jan/2005:17:32:25 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 8, used=0, file=-1
D [31/Jan/2005:17:32:25 +0100] ReadClient() 8 POST /printers/ HTTP/1.1
d [31/Jan/2005:17:32:25 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:25 +0100] decode_auth() 8 username=""
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:17:32:25 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:17:32:25 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:25 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:25 +0100] FindBest: best = "/"
d [31/Jan/2005:17:32:25 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:25 +0100] POST /printers/
d [31/Jan/2005:17:32:25 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:25 +0100] ReadClient() 8 con->data_encoding = length, 
con->data_remaining = 112, con->file = -1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest(0x40345008[8]): operation_id 
= 4001
d [31/Jan/2005:17:32:25 +0100] get_default(0x40345008[8])
d [31/Jan/2005:17:32:25 +0100] copy_attrs(0x80a26a0, 0x80929d0, 0x80a2320, 0)
d [31/Jan/2005:17:32:25 +0100] copy_attribute(0x80a26a0, 0x823f300
[printer-name,4,42])
d [31/Jan/2005:17:32:25 +0100] copy_attrs(0x80a26a0, 0x8089610, 0x80a2320, 0)
D [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: 8 status_code=1
d [31/Jan/2005:17:32:25 +0100] ProcessIPPRequest: Adding fd 8 to OutputSet...
d [31/Jan/2005:17:32:25 +0100] WriteClient: Removing fd 8 from OutputSet...
d [31/Jan/2005:17:32:25 +0100] ReadClient() 8, used=0, file=-1
d [31/Jan/2005:17:32:25 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:25 +0100] CloseClient() 8
d [31/Jan/2005:17:32:25 +0100] CloseClient: Removing fd 8 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:26 +0100] SendBrowseList: (139 bytes to 7f000001) 301e 3 
ipp://euklid:631/printers/leda "Erdgeschoss" "Canon iR 2200" "Generic 
PostScript level 2 Printer Foomatic/Postscript (recommended)"
d [31/Jan/2005:17:32:26 +0100] UpdateCUPSBrowse: (139 bytes from 127.0.0.1) 
301e 3 ipp://euklid:631/printers/leda "Erdgeschoss" "Canon iR 2200" "Generic 
PostScript level 2 Printer Foomatic/Postscript (recommended)"
d [31/Jan/2005:17:32:38 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:17:32:38 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:17:32:38 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:17:32:38 +0100] ReadClient() 7 POST /printers/ HTTP/1.1
d [31/Jan/2005:17:32:38 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:38 +0100] decode_auth() 7 username=""
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:17:32:38 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:17:32:38 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: best = "/"
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:38 +0100] POST /printers/
d [31/Jan/2005:17:32:38 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 216, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 187, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 150, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=112, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 113, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 4002
d [31/Jan/2005:17:32:38 +0100] get_printers(0x40345008[7], 0)
d [31/Jan/2005:17:32:38 +0100] add_printer_state_reasons(0x40345008[7], 
0x80951a0[leda])
d [31/Jan/2005:17:32:38 +0100] add_queued_job_count(0x40345008[7], 0x80951a0
[leda])
d [31/Jan/2005:17:32:38 +0100] copy_attrs(0x823dfa8, 0x80929d0, 0x80a2320, 0)
d [31/Jan/2005:17:32:38 +0100] copy_attribute(0x823dfa8, 0x8240178
[printer-uri-supported,4,45])
d [31/Jan/2005:17:32:38 +0100] copy_attribute(0x823dfa8, 0x823f300
[printer-name,4,42])
d [31/Jan/2005:17:32:38 +0100] copy_attribute(0x823dfa8, 0x823f0e8
[printer-location,4,41])
d [31/Jan/2005:17:32:38 +0100] copy_attribute(0x823dfa8, 0x80a1e68
[printer-type,4,23])
d [31/Jan/2005:17:32:38 +0100] copy_attrs(0x823dfa8, 0x8089610, 0x80a2320, 0)
D [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: 7 status_code=1
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:17:32:38 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:38 +0100] CloseClient() 7
d [31/Jan/2005:17:32:38 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:38 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:17:32:38 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:17:32:38 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:17:32:38 +0100] ReadClient() 7 POST /classes/ HTTP/1.1
d [31/Jan/2005:17:32:38 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:38 +0100] decode_auth() 7 username=""
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: con->uri = "/classes/"
d [31/Jan/2005:17:32:38 +0100] FindBest: uri = "/classes/"...
d [31/Jan/2005:17:32:38 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: best = "/classes"
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:38 +0100] POST /classes/
d [31/Jan/2005:17:32:38 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 216, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 187, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 150, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=112, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 113, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 4005
d [31/Jan/2005:17:32:38 +0100] get_printers(0x40345008[7], 1)
D [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: 7 status_code=1
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:17:32:38 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:38 +0100] CloseClient() 7
d [31/Jan/2005:17:32:38 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:38 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:17:32:38 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:17:32:38 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:17:32:38 +0100] ReadClient() 7 POST /printers/ HTTP/1.1
d [31/Jan/2005:17:32:38 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:38 +0100] decode_auth() 7 username=""
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:17:32:38 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:17:32:38 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: best = "/"
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:38 +0100] POST /printers/
d [31/Jan/2005:17:32:38 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 112, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 104, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 75, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 38, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 1, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 4001
d [31/Jan/2005:17:32:38 +0100] get_default(0x40345008[7])
d [31/Jan/2005:17:32:38 +0100] copy_attrs(0x80a2580, 0x80929d0, 0x80a2320, 0)
d [31/Jan/2005:17:32:38 +0100] copy_attribute(0x80a2580, 0x823f300
[printer-name,4,42])
d [31/Jan/2005:17:32:38 +0100] copy_attrs(0x80a2580, 0x8089610, 0x80a2320, 0)
D [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: 7 status_code=1
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:17:32:38 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:38 +0100] CloseClient() 7
d [31/Jan/2005:17:32:38 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:38 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:17:32:38 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:17:32:38 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:17:32:38 +0100] ReadClient() 7 POST / HTTP/1.1
d [31/Jan/2005:17:32:38 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:17:32:38 +0100] decode_auth() 7 username=""
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: con->uri = "/"
d [31/Jan/2005:17:32:38 +0100] FindBest: uri = "/"...
d [31/Jan/2005:17:32:38 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:17:32:38 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:17:32:38 +0100] FindBest: best = "/"
d [31/Jan/2005:17:32:38 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:17:32:38 +0100] POST /
d [31/Jan/2005:17:32:38 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 395, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 387, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 358, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 321, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 250, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=218, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 219, con->file = -1
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 000a
d [31/Jan/2005:17:32:38 +0100] get_jobs(0x40345008[7], 
ipp://localhost:631/printers/Print to File (PostScript))
E [31/Jan/2005:17:32:38 +0100] get_jobs: resource name '/printers/Print to 
File (PostScript)' no good!
d [31/Jan/2005:17:32:38 +0100] send_ipp_error(0x40345008[7], 406)
D [31/Jan/2005:17:32:38 +0100] Sending error: client-error-not-found
D [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: 7 status_code=406
d [31/Jan/2005:17:32:38 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:17:32:38 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:17:32:38 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:17:32:38 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:17:32:38 +0100] CloseClient() 7
d [31/Jan/2005:17:32:38 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:17:32:57 +0100] SendBrowseList: (139 bytes to 7f000001) 301e 3 
ipp://euklid:631/printers/leda "Erdgeschoss" "Canon iR 2200" "Generic 
PostScript level 2 Printer Foomatic/Postscript (recommended)"
d [31/Jan/2005:17:32:57 +0100] UpdateCUPSBrowse: (139 bytes from 127.0.0.1) 
301e 3 ipp://euklid:631/printers/leda "Erdgeschoss" "Canon iR 2200" "Generic 
PostScript level 2 Printer Foomatic/Postscript (recommended)"
d [31/Jan/2005:17:33:03 +0100] DeleteCert: removing certificate for pid 0
d [31/Jan/2005:17:33:03 +0100] AddCert: adding certificate for pid 0