kprinter doesn't work together with CUPS

[Markus Kitzler]

> Do you have debug output when you start an app in a terminal, it would
> help to see kdeprint's related message when you print something.
> When you print to a PS file first, then print it using "lpr", does it
> work?
> Something else: using CUPS plugin, in the printer properties dialog,
> in the "Margins" tab, can you check the margins validity?

The kdeprint bug-output is activated with kdebugdialog at section 500, 
nevertheless I do not get messages in my terminal when I print something.
Therefore I have attached the output of /var/log/cups/error_log which I get 
when I use kghostview to print (with no success) to my printer (leda) (see 
below).
The displayed "Margins" in the properties dialog seem to be reasonable.

d [31/Jan/2005:11:50:12 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:11:50:12 +0100] AcceptClient: 5 from localhost:631.
d [31/Jan/2005:11:50:12 +0100] AcceptClient: Adding fd 5 to InputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:12 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:12 +0100] CloseClient() 5
d [31/Jan/2005:11:50:12 +0100] CloseClient: Removing fd 5 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:12 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:11:50:12 +0100] AcceptClient: 5 from localhost:631.
d [31/Jan/2005:11:50:12 +0100] AcceptClient: Adding fd 5 to InputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 5, used=0, file=-1
D [31/Jan/2005:11:50:12 +0100] ReadClient() 5 POST /printers/ HTTP/1.1
d [31/Jan/2005:11:50:12 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:12 +0100] decode_auth() 5 username=""
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:11:50:12 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:11:50:12 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: best = "/"
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:12 +0100] POST /printers/
d [31/Jan/2005:11:50:12 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:12 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 112, con->file = -1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest(0x40345008[5]): operation_id 
= 4002
d [31/Jan/2005:11:50:12 +0100] get_printers(0x40345008[5], 0)
d [31/Jan/2005:11:50:12 +0100] add_printer_state_reasons(0x40345008[5], 
0x80951a0[leda])
d [31/Jan/2005:11:50:12 +0100] add_queued_job_count(0x40345008[5], 0x80951a0
[leda])
d [31/Jan/2005:11:50:12 +0100] copy_attrs(0x8254928, 0x80929d0, 0x8239550, 0)
d [31/Jan/2005:11:50:12 +0100] copy_attribute(0x8254928, 0x8092b30
[printer-name,4,42])
d [31/Jan/2005:11:50:12 +0100] copy_attrs(0x8254928, 0x8089610, 0x8239550, 0)
D [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: 5 status_code=1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [31/Jan/2005:11:50:12 +0100] WriteClient: Removing fd 5 from OutputSet...
d [31/Jan/2005:11:50:12 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 1
D [31/Jan/2005:11:50:12 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:11:50:12 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:12 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:12 +0100] CloseClient() 5
d [31/Jan/2005:11:50:12 +0100] CloseClient: Removing fd 5 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:11:50:12 +0100] ReadClient() 7 POST /printers/ HTTP/1.1
d [31/Jan/2005:11:50:12 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:12 +0100] decode_auth() 7 username=""
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:11:50:12 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:11:50:12 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: best = "/"
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:12 +0100] POST /printers/
d [31/Jan/2005:11:50:12 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:12 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 4002
d [31/Jan/2005:11:50:12 +0100] get_printers(0x40345008[7], 0)
d [31/Jan/2005:11:50:12 +0100] add_printer_state_reasons(0x40345008[7], 
0x80951a0[leda])
d [31/Jan/2005:11:50:12 +0100] add_queued_job_count(0x40345008[7], 0x80951a0
[leda])
d [31/Jan/2005:11:50:12 +0100] copy_attrs(0x8254790, 0x80929d0, 0x8239550, 0)
d [31/Jan/2005:11:50:12 +0100] copy_attribute(0x8254790, 0x80929f8
[printer-uri-supported,4,45])
d [31/Jan/2005:11:50:12 +0100] copy_attribute(0x8254790, 0x8092b30
[printer-name,4,42])
d [31/Jan/2005:11:50:12 +0100] copy_attribute(0x8254790, 0x8092b80
[printer-location,4,41])
d [31/Jan/2005:11:50:12 +0100] copy_attribute(0x8254790, 0x809f948
[printer-type,4,23])
d [31/Jan/2005:11:50:12 +0100] copy_attrs(0x8254790, 0x8089610, 0x8239550, 0)
D [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: 7 status_code=1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:11:50:12 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:11:50:12 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 1
D [31/Jan/2005:11:50:12 +0100] AcceptClient: 5 from localhost:631.
d [31/Jan/2005:11:50:12 +0100] AcceptClient: Adding fd 5 to InputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:11:50:12 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:12 +0100] CloseClient() 7
d [31/Jan/2005:11:50:12 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 5, used=0, file=-1
D [31/Jan/2005:11:50:12 +0100] ReadClient() 5 POST /classes/ HTTP/1.1
d [31/Jan/2005:11:50:12 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:12 +0100] decode_auth() 5 username=""
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: con->uri = "/classes/"
d [31/Jan/2005:11:50:12 +0100] FindBest: uri = "/classes/"...
d [31/Jan/2005:11:50:12 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: best = "/classes"
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:12 +0100] POST /classes/
d [31/Jan/2005:11:50:12 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:12 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest(0x40345008[5]): operation_id 
= 4005
d [31/Jan/2005:11:50:12 +0100] get_printers(0x40345008[5], 1)
D [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: 5 status_code=1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [31/Jan/2005:11:50:12 +0100] WriteClient: Removing fd 5 from OutputSet...
d [31/Jan/2005:11:50:12 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 1
D [31/Jan/2005:11:50:12 +0100] AcceptClient: 7 from localhost:631.
d [31/Jan/2005:11:50:12 +0100] AcceptClient: Adding fd 7 to InputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:12 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:12 +0100] CloseClient() 5
d [31/Jan/2005:11:50:12 +0100] CloseClient: Removing fd 5 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 7, used=0, file=-1
D [31/Jan/2005:11:50:12 +0100] ReadClient() 7 POST /printers/ HTTP/1.1
d [31/Jan/2005:11:50:12 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:12 +0100] decode_auth() 7 username=""
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:11:50:12 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:11:50:12 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:12 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:12 +0100] FindBest: best = "/"
d [31/Jan/2005:11:50:12 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:12 +0100] POST /printers/
d [31/Jan/2005:11:50:12 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:12 +0100] ReadClient() 7 con->data_encoding = length, 
con->data_remaining = 112, con->file = -1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest(0x40345008[7]): operation_id 
= 4001
d [31/Jan/2005:11:50:12 +0100] get_default(0x40345008[7])
d [31/Jan/2005:11:50:12 +0100] copy_attrs(0x82548e0, 0x80929d0, 0x8239550, 0)
d [31/Jan/2005:11:50:12 +0100] copy_attribute(0x82548e0, 0x8092b30
[printer-name,4,42])
d [31/Jan/2005:11:50:12 +0100] copy_attrs(0x82548e0, 0x8089610, 0x8239550, 0)
D [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: 7 status_code=1
d [31/Jan/2005:11:50:12 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [31/Jan/2005:11:50:12 +0100] WriteClient: Removing fd 7 from OutputSet...
d [31/Jan/2005:11:50:12 +0100] ReadClient() 7, used=0, file=-1
d [31/Jan/2005:11:50:12 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:12 +0100] CloseClient() 7
d [31/Jan/2005:11:50:12 +0100] CloseClient: Removing fd 7 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:16 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:11:50:16 +0100] AcceptClient: 5 from localhost:631.
d [31/Jan/2005:11:50:16 +0100] AcceptClient: Adding fd 5 to InputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
D [31/Jan/2005:11:50:16 +0100] ReadClient() 5 POST /printers/ HTTP/1.1
d [31/Jan/2005:11:50:16 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:16 +0100] decode_auth() 5 username=""
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:11:50:16 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:11:50:16 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: best = "/"
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:16 +0100] POST /printers/
d [31/Jan/2005:11:50:16 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 216, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 187, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 150, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=112, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 113, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest(0x40345008[5]): operation_id 
= 4002
d [31/Jan/2005:11:50:16 +0100] get_printers(0x40345008[5], 0)
d [31/Jan/2005:11:50:16 +0100] add_printer_state_reasons(0x40345008[5], 
0x80951a0[leda])
d [31/Jan/2005:11:50:16 +0100] add_queued_job_count(0x40345008[5], 0x80951a0
[leda])
d [31/Jan/2005:11:50:16 +0100] copy_attrs(0x8254998, 0x80929d0, 0x8239550, 0)
d [31/Jan/2005:11:50:16 +0100] copy_attribute(0x8254998, 0x80929f8
[printer-uri-supported,4,45])
d [31/Jan/2005:11:50:16 +0100] copy_attribute(0x8254998, 0x8092b30
[printer-name,4,42])
d [31/Jan/2005:11:50:16 +0100] copy_attribute(0x8254998, 0x8092b80
[printer-location,4,41])
d [31/Jan/2005:11:50:16 +0100] copy_attribute(0x8254998, 0x809f948
[printer-type,4,23])
d [31/Jan/2005:11:50:16 +0100] copy_attrs(0x8254998, 0x8089610, 0x8239550, 0)
D [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: 5 status_code=1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [31/Jan/2005:11:50:16 +0100] WriteClient: Removing fd 5 from OutputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:16 +0100] CloseClient() 5
d [31/Jan/2005:11:50:16 +0100] CloseClient: Removing fd 5 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:16 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:11:50:16 +0100] AcceptClient: 5 from localhost:631.
d [31/Jan/2005:11:50:16 +0100] AcceptClient: Adding fd 5 to InputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
D [31/Jan/2005:11:50:16 +0100] ReadClient() 5 POST /classes/ HTTP/1.1
d [31/Jan/2005:11:50:16 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:16 +0100] decode_auth() 5 username=""
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: con->uri = "/classes/"
d [31/Jan/2005:11:50:16 +0100] FindBest: uri = "/classes/"...
d [31/Jan/2005:11:50:16 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: best = "/classes"
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:16 +0100] POST /classes/
d [31/Jan/2005:11:50:16 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 224, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 216, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 187, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 150, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=112, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 113, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest(0x40345008[5]): operation_id 
= 4005
d [31/Jan/2005:11:50:16 +0100] get_printers(0x40345008[5], 1)
D [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: 5 status_code=1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [31/Jan/2005:11:50:16 +0100] WriteClient: Removing fd 5 from OutputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:16 +0100] CloseClient() 5
d [31/Jan/2005:11:50:16 +0100] CloseClient: Removing fd 5 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:16 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:11:50:16 +0100] AcceptClient: 5 from localhost:631.
d [31/Jan/2005:11:50:16 +0100] AcceptClient: Adding fd 5 to InputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
D [31/Jan/2005:11:50:16 +0100] ReadClient() 5 POST /printers/ HTTP/1.1
d [31/Jan/2005:11:50:16 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:16 +0100] decode_auth() 5 username=""
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: con->uri = "/printers/"
d [31/Jan/2005:11:50:16 +0100] FindBest: uri = "/printers/"...
d [31/Jan/2005:11:50:16 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: best = "/"
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:16 +0100] POST /printers/
d [31/Jan/2005:11:50:16 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 112, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 104, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 75, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 38, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 1, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest(0x40345008[5]): operation_id 
= 4001
d [31/Jan/2005:11:50:16 +0100] get_default(0x40345008[5])
d [31/Jan/2005:11:50:16 +0100] copy_attrs(0x8254960, 0x80929d0, 0x8239550, 0)
d [31/Jan/2005:11:50:16 +0100] copy_attribute(0x8254960, 0x8092b30
[printer-name,4,42])
d [31/Jan/2005:11:50:16 +0100] copy_attrs(0x8254960, 0x8089610, 0x8239550, 0)
D [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: 5 status_code=1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [31/Jan/2005:11:50:16 +0100] WriteClient: Removing fd 5 from OutputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:16 +0100] CloseClient() 5
d [31/Jan/2005:11:50:16 +0100] CloseClient: Removing fd 5 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:16 +0100] AcceptClient(lis=0x8087708) 0 NumClients = 0
D [31/Jan/2005:11:50:16 +0100] AcceptClient: 5 from localhost:631.
d [31/Jan/2005:11:50:16 +0100] AcceptClient: Adding fd 5 to InputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
D [31/Jan/2005:11:50:16 +0100] ReadClient() 5 POST / HTTP/1.1
d [31/Jan/2005:11:50:16 +0100] decode_auth(0x40345008): Authorization string = 
""
d [31/Jan/2005:11:50:16 +0100] decode_auth() 5 username=""
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: con->uri = "/"
d [31/Jan/2005:11:50:16 +0100] FindBest: uri = "/"...
d [31/Jan/2005:11:50:16 +0100] FindBest: Location / Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /admin Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: Location CUPS_INTERNAL_BROWSE_ACL 
Limit 0
d [31/Jan/2005:11:50:16 +0100] FindBest: Location /classes Limit 7f
d [31/Jan/2005:11:50:16 +0100] FindBest: best = "/"
d [31/Jan/2005:11:50:16 +0100] IsAuthorized: auth = 0, satisfy=0...
d [31/Jan/2005:11:50:16 +0100] POST /
d [31/Jan/2005:11:50:16 +0100] CONTENT_TYPE = application/ipp
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 373, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 365, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 336, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 299, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 250, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=218, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5 con->data_encoding = length, 
con->data_remaining = 219, con->file = -1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest(0x40345008[5]): operation_id 
= 000a
d [31/Jan/2005:11:50:16 +0100] get_jobs(0x40345008[5], 
ipp://localhost:631/printers/leda)
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 1
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 2
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 3
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 4
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 5
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 6
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 7
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 8
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 9
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 10
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 11
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 12
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 13
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 14
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 15
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 16
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 17
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 18
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 19
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 20
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 21
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 22
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 23
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 24
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 25
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 26
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 27
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 28
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 29
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 30
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 31
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 32
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 33
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 34
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 35
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 36
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 37
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 38
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 39
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 40
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 41
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 42
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 43
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 44
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 45
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 46
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 47
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 48
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 49
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 50
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 51
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 52
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 53
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 54
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 55
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 56
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 57
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 58
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 59
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 60
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 61
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 62
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 63
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 64
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 65
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 66
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 67
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 68
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 69
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 70
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 71
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 72
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 73
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 74
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 75
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 76
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 77
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 78
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 79
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 80
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 81
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 82
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 83
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 84
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 85
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 86
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 87
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 88
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 89
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 90
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 91
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 92
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 93
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 94
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 95
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 96
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 97
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 98
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 99
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 100
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 101
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 102
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 103
d [31/Jan/2005:11:50:16 +0100] get_jobs: job->id = 104
D [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: 5 status_code=1
d [31/Jan/2005:11:50:16 +0100] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [31/Jan/2005:11:50:16 +0100] WriteClient: Removing fd 5 from OutputSet...
d [31/Jan/2005:11:50:16 +0100] ReadClient() 5, used=0, file=-1
d [31/Jan/2005:11:50:16 +0100] ReadClient: httpGets returned EOF...
D [31/Jan/2005:11:50:16 +0100] CloseClient() 5
d [31/Jan/2005:11:50:16 +0100] CloseClient: Removing fd 5 from InputSet and 
OutputSet...
d [31/Jan/2005:11:50:23 +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:11:50:23 +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)"