LPD client with CUPS server -> impossible to print !!

CASTAGNET Fabien fabien.castagnet at external.thalesgroup.com
Wed Oct 22 09:32:08 PDT 2008 

Hello,
I have a configuration client/sever with:
- a LPD client
- a CUPS server (1.1.20)
- a printer installed on CUPS server

following tests have been done:
- LPD client work properly (tested with another meaning)
- printer is correctly installed (able to print test page, able to print all kind of files from lpr commands(ipp))

*-*-*-*-*-*-*-*-*-*
issue description :
*-*-*-*-*-*-*-*-*-*
1/lpd client send job on queue, and all is ok with no errors coming back
2/ xinetd received connexion, launch cups-lpd daemon, and cupsd received data correctly without errors
3/ **BUT** once data received, the data are NOT sent to printer (via backend)  ????!!!??????

????????????????????????????????????
=> is it already known CUPS bug or is it my settings decribed below
????????????????????????????????????

My configuration on server side is as recommanded on cups users manual:
    ------ xinetd with following cups-lpd file setting -----
    --------------------------------------------------------
service printer
{
  disable = no
  socket_type = stream
  protocol = tcp
  wait = no
  user = lp
  group = sys
  passenv =
  server = /usr/lib/cups/daemon/cups-lpd
  server_args = -o document-format=application/octet-stream
}

   ---- cupsd have the following setting ---
   -----------------------------------------
# Log general information in error_log - change "info" to "debug" for
# troubleshooting...
LogLevel debug2

# Administrator user group...
SystemGroup sys root

# Only listen for connections from the local machine.
Listen localhost:631
Listen 172.17.0.217:631

# Show shared printers on the local network.
Browsing On
BrowseOrder allow,deny
# (Change '@LOCAL' to 'ALL' if using directed broadcasts from another subnet.)
BrowseAllow ALL

# Default authentication type, when authentication is required...
#DefaultAuthType Basic

# Restrict access to the server...
<Location />
  Order allow,deny
  Allow from All
</Location>

# Restrict access to configuration files...
<Location /admin/conf>
  AuthType Basic
  Require user @SYSTEM
  Order allow,deny
  Allow from all
</Location>

*****************************************************
*** cups traces on /var/log/cups/error_log are:  ****
*****************************************************

d [01/Jan/1970:05:39:39 +0000] AcceptClient(0x10047970) 0 NumClients = 0
D [01/Jan/1970:05:39:39 +0000] AcceptClient() 5 from localhost:631.
d [01/Jan/1970:05:39:39 +0000] AcceptClient: Adding fd 5 to InputSet...
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
D [01/Jan/1970:05:39:39 +0000] ReadClient() 5 POST / HTTP/1.1
d [01/Jan/1970:05:39:39 +0000] decode_auth(0x30022008): Authorization string =
d [01/Jan/1970:05:39:39 +0000] decode_auth() 5 username=
d [01/Jan/1970:05:39:39 +0000] IsAuthorized: con->uri = /
d [01/Jan/1970:05:39:39 +0000] FindBest: uri = /...
d [01/Jan/1970:05:39:39 +0000] FindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [01/Jan/1970:05:39:39 +0000] FindBest: Location / Limit 7f
d [01/Jan/1970:05:39:39 +0000] FindBest: Location /admin/conf Limit 7f
d [01/Jan/1970:05:39:39 +0000] FindBest: best = /
d [01/Jan/1970:05:39:39 +0000] IsAuthorized: auth = 0, satisfy=0...
d [01/Jan/1970:05:39:39 +0000] POST /
d [01/Jan/1970:05:39:39 +0000] CONTENT_TYPE = application/ipp
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 137, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 129, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 95, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 61, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=23
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 24, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest(0x30022008[5]): operation_id = 4002
d [01/Jan/1970:05:39:39 +0000] get_printers(0x30022008[5], 0)
d [01/Jan/1970:05:39:39 +0000] add_printer_state_reasons(0x30022008[5], 0x10054200[ThalesPrinter])
d [01/Jan/1970:05:39:39 +0000] add_queued_job_count(0x30022008[5], 0x10054200[ThalesPrinter])
d [01/Jan/1970:05:39:39 +0000] copy_attrs(0x10069390, 0x10051b40, 0x1006a490, 0)
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069390, 0x10051ca8[printer-name,4,42])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069390, 0x10051ed0[job-sheets-default,4,42])
d [01/Jan/1970:05:39:39 +0000] copy_attrs(0x10069390, 0x1004e718, 0x1006a490, 0)
D [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest: 5 status_code=1
d [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [01/Jan/1970:05:39:39 +0000] WriteClient: Removing fd 5 from OutputSet...
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
D [01/Jan/1970:05:39:39 +0000] CloseClient() 5
d [01/Jan/1970:05:39:39 +0000] CloseClient: Removing fd 5 from InputSet and OutputSet...
d [01/Jan/1970:05:39:39 +0000] AcceptClient(0x10047970) 0 NumClients = 0
D [01/Jan/1970:05:39:39 +0000] AcceptClient() 5 from localhost:631.
d [01/Jan/1970:05:39:39 +0000] AcceptClient: Adding fd 5 to InputSet...
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
D [01/Jan/1970:05:39:39 +0000] ReadClient() 5 POST / HTTP/1.1
d [01/Jan/1970:05:39:39 +0000] decode_auth(0x30022008): Authorization string =
d [01/Jan/1970:05:39:39 +0000] decode_auth() 5 username=
d [01/Jan/1970:05:39:39 +0000] IsAuthorized: con->uri = /
d [01/Jan/1970:05:39:39 +0000] FindBest: uri = /...
d [01/Jan/1970:05:39:39 +0000] FindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [01/Jan/1970:05:39:39 +0000] FindBest: Location / Limit 7f
d [01/Jan/1970:05:39:39 +0000] FindBest: Location /admin/conf Limit 7f
d [01/Jan/1970:05:39:39 +0000] FindBest: best = /
d [01/Jan/1970:05:39:39 +0000] IsAuthorized: auth = 0, satisfy=0...
d [01/Jan/1970:05:39:39 +0000] POST /
d [01/Jan/1970:05:39:39 +0000] CONTENT_TYPE = application/ipp
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 137, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 129, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 95, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 61, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=23
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 24, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest(0x30022008[5]): operation_id = 4005
d [01/Jan/1970:05:39:39 +0000] get_printers(0x30022008[5], 1)
D [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest: 5 status_code=1
d [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [01/Jan/1970:05:39:39 +0000] WriteClient: Removing fd 5 from OutputSet...
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
D [01/Jan/1970:05:39:39 +0000] CloseClient() 5
d [01/Jan/1970:05:39:39 +0000] CloseClient: Removing fd 5 from InputSet and OutputSet...
d [01/Jan/1970:05:39:39 +0000] AcceptClient(0x10047970) 0 NumClients = 0
D [01/Jan/1970:05:39:39 +0000] AcceptClient() 5 from localhost:631.
d [01/Jan/1970:05:39:39 +0000] AcceptClient: Adding fd 5 to InputSet...
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
D [01/Jan/1970:05:39:39 +0000] ReadClient() 5 POST / HTTP/1.1
d [01/Jan/1970:05:39:39 +0000] decode_auth(0x30022008): Authorization string =
d [01/Jan/1970:05:39:39 +0000] decode_auth() 5 username=
d [01/Jan/1970:05:39:39 +0000] IsAuthorized: con->uri = /
d [01/Jan/1970:05:39:39 +0000] FindBest: uri = /...
d [01/Jan/1970:05:39:39 +0000] FindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
d [01/Jan/1970:05:39:39 +0000] FindBest: Location / Limit 7f
d [01/Jan/1970:05:39:39 +0000] FindBest: Location /admin/conf Limit 7f
d [01/Jan/1970:05:39:39 +0000] FindBest: best = /
d [01/Jan/1970:05:39:39 +0000] IsAuthorized: auth = 0, satisfy=0...
d [01/Jan/1970:05:39:39 +0000] POST /
d [01/Jan/1970:05:39:39 +0000] CONTENT_TYPE = application/ipp
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 77, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 69, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 35, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 1, con->file = 0
d [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest(0x30022008[5]): operation_id = 4001
d [01/Jan/1970:05:39:39 +0000] get_default(0x30022008[5])
d [01/Jan/1970:05:39:39 +0000] copy_attrs(0x10069520, 0x10051b40, (nil), 0)
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051b68[printer-uri-supported,4,45])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051be0[uri-authentication-supported,4,44])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051c50[uri-security-supported,4,44])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051ca8[printer-name,4,42])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051d00[printer-location,4,41])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051d50[printer-info,4,41])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051da8[printer-more-info,4,45])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051e18[job-quota-period,4,21])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051e58[job-k-limit,4,21])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051e90[job-page-limit,4,21])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051ed0[job-sheets-default,4,42])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051f38[device-uri,4,45])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x1005d330[color-supported,4,22])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x1005d0b0[pages-per-minute,4,21])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x1005d108[printer-make-and-model,4,41])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10051fd0[media-supported,4,44])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x10052010[media-default,4,44])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x1005d698[finishings-supported,4,23])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x1005d510[finishings-default,4,23])
d [01/Jan/1970:05:39:39 +0000] copy_attribute(0x10069520, 0x1005d4d8[printer-type,4,23])
D [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest: 5 status_code=0
d [01/Jan/1970:05:39:39 +0000] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [01/Jan/1970:05:39:39 +0000] WriteClient: Removing fd 5 from OutputSet...
d [01/Jan/1970:05:39:39 +0000] ReadClient() 5, used=0
D [01/Jan/1970:05:39:39 +0000] CloseClient() 5
d [01/Jan/1970:05:39:39 +0000] CloseClient: Removing fd 5 from InputSet and OutputSet...


*** and no more traces ??!!??!!??? ***



can you help me????????? or give me some advices ???

More information about the cups mailing list