Hello <br>My name is Remon and I would like to know your opinion about the next problem and my ldap module configuration:<br><br>I have an authentication system base on: freeradius-2.1.7 and OpenLdap 2.3.30.<br>Our users are authenticated against LDAP and it is getting back a role (student or Employee).<br>
Recently I have changed the ldap topology and after that we are having a lot of problems.<br>the radius log show us a lot of lines like:<br>Error: Discarding duplicate request from client xxxxxxx port 32841 - ID: 112 due to unfinished request 421<br>
Error: rlm_ldap: All ldap connections are in use<br><br>I read some post about this problem, and they say that the problem could be a slow connection with Ldap or a indexing problem of the ldap, <br>My question is, <br> if the reason of this problem could be my ldap module configuration and the type of filter that I use for searching the users in the diferents groups or only depend on ldap server configuration?<br>
<br>First of all, my new ldap structure is like:<br><br>dc=company, dc=com--------------------------------------ou--------------------------------------------ou=radius (groupOfNames, radiusprofile)<br> | | | cn=student<br>
| |ou=groups (groupOfNames) vlan-parameters<br> cn=users |- cn=student <br>
member (user1,......) | cn= employee<br> | |- cn=employee vlan-parameters<br>
|- user1 member (user2,......)<br> |- user2<br> |- .......<br><br>My server version is freeradius-sderver-2.1.7<br><br>My ldap module configuration is:<br>
<br>server = "xxx.xxx.xxx.xxx"<br><br> identity = "cn=xxxx,dc=yyy,dc=zz"<br> password = sssss<br><br> basedn = "dc=yyy,dc=zz"<br> filter = "(uid=%{Stripped-User-Name})"<br>
<br> groupmembership_filter = "(&(objectClass=groupOfNames)(member=%{control:LDAP-UserDn}))"<br><br> access_attr = "uid"<br> password_attribute = Cleartext-Password<br> ldap_connections_number = 200<br>
timeout = 4<br> timelimit = 3<br> net_timeout = 1<br><br> tls {<br> start_tls = no<br> }<br> dictionary_mapping = ${confdir}/ldap.attrmap<br> edir_account_policy_check = no<br>
<br><br><br>I send you the output when a user "student13" is authenticated :<br>Tue Nov 8 10:05:54 2011 : Info: FreeRADIUS Version 2.1.7, for host i686-pc-linux-gnu, built on Feb 11 2010 at 17:23:35<br>Tue Nov 8 10:05:54 2011 : Info: Copyright (C) 1999-2009 The FreeRADIUS server project and contributors. <br>
Tue Nov 8 10:05:54 2011 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A <br>Tue Nov 8 10:05:54 2011 : Info: PARTICULAR PURPOSE. <br>Tue Nov 8 10:05:54 2011 : Info: You may redistribute copies of FreeRADIUS under the terms of the <br>
Tue Nov 8 10:05:54 2011 : Info: GNU General Public License v2. <br>Tue Nov 8 10:05:54 2011 : Info: Starting - reading configuration files ...<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/radiusd.conf<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/proxy.conf<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/clients.conf<br>Tue Nov 8 10:05:54 2011 : Debug: including files in directory /usr/local/etc/raddb/modules/<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/policy<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/unix<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/echo<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/smbpasswd<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/acct_unique<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/files<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/detail.log<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/checkval<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/etc_group<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/pam<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/ippool<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/chap<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/detail<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/krb5<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/passwd<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/inner-eap<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/<a href="http://detail.example.com">detail.example.com</a><br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/logintime<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/expiration<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2ip<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_filter<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/smsotp<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/mschap<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/counter<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/pap<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/digest<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/expr<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/preprocess<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/sradutmp<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/otp<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/wimax<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/realm<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/radutmp<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/perl<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/cui<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2vlan<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/linelog<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/always<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/sqlcounter_expire_on_login<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/exec<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/sql_log<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/ldap<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_rewrite<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/eap.conf<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/policy.conf<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/default<br>
Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/control-socket<br>Tue Nov 8 10:05:54 2011 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel<br>
Tue Nov 8 10:05:54 2011 : Debug: group = freerad<br>Tue Nov 8 10:05:54 2011 : Debug: user = freerad<br>Tue Nov 8 10:05:54 2011 : Debug: including dictionary file /usr/local/etc/raddb/dictionary<br>Tue Nov 8 10:05:54 2011 : Debug: main {<br>
Tue Nov 8 10:05:54 2011 : Debug: prefix = "/usr/local"<br>Tue Nov 8 10:05:54 2011 : Debug: localstatedir = "/usr/local/var"<br>Tue Nov 8 10:05:54 2011 : Debug: logdir = "/usr/local/var/log/radius"<br>
Tue Nov 8 10:05:54 2011 : Debug: libdir = "/usr/local/lib"<br>Tue Nov 8 10:05:54 2011 : Debug: radacctdir = "/usr/local/var/log/radius/radacct"<br>Tue Nov 8 10:05:54 2011 : Debug: hostname_lookups = no<br>
Tue Nov 8 10:05:54 2011 : Debug: max_request_time = 30<br>Tue Nov 8 10:05:54 2011 : Debug: cleanup_delay = 5<br>Tue Nov 8 10:05:54 2011 : Debug: max_requests = 1024<br>Tue Nov 8 10:05:54 2011 : Debug: allow_core_dumps = no<br>
Tue Nov 8 10:05:54 2011 : Debug: pidfile = "/usr/local/var/run/radiusd/radiusd.pid"<br>Tue Nov 8 10:05:54 2011 : Debug: checkrad = "/usr/local/sbin/checkrad"<br>Tue Nov 8 10:05:54 2011 : Debug: debug_level = 0<br>
Tue Nov 8 10:05:54 2011 : Debug: proxy_requests = yes<br>Tue Nov 8 10:05:54 2011 : Debug: log {<br>Tue Nov 8 10:05:54 2011 : Debug: stripped_names = no<br>Tue Nov 8 10:05:54 2011 : Debug: auth = yes<br>Tue Nov 8 10:05:54 2011 : Debug: auth_badpass = no<br>
Tue Nov 8 10:05:54 2011 : Debug: auth_goodpass = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: security {<br>Tue Nov 8 10:05:54 2011 : Debug: max_attributes = 200<br>Tue Nov 8 10:05:54 2011 : Debug: reject_delay = 1<br>
Tue Nov 8 10:05:54 2011 : Debug: status_server = yes<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: radiusd: #### Loading Realms and Home Servers ####<br>
Tue Nov 8 10:05:54 2011 : Debug: proxy server {<br>Tue Nov 8 10:05:54 2011 : Debug: retry_delay = 5<br>Tue Nov 8 10:05:54 2011 : Debug: retry_count = 3<br>Tue Nov 8 10:05:54 2011 : Debug: default_fallback = yes<br>
Tue Nov 8 10:05:54 2011 : Debug: dead_time = 120<br>Tue Nov 8 10:05:54 2011 : Debug: wake_all_if_all_dead = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: realm LOCAL {<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: realm NULL {<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: home_server radius_proxy {<br>Tue Nov 8 10:05:54 2011 : Debug: ipaddr = xxx.xxx.xxx.xxx<br>
Tue Nov 8 10:05:54 2011 : Debug: port = 1812<br>Tue Nov 8 10:05:54 2011 : Debug: type = "auth+acct"<br>Tue Nov 8 10:05:54 2011 : Debug: secret = "xxxxxxx"<br>Tue Nov 8 10:05:54 2011 : Debug: response_window = 20<br>
Tue Nov 8 10:05:54 2011 : Debug: max_outstanding = 65536<br>Tue Nov 8 10:05:54 2011 : Debug: zombie_period = 40<br>Tue Nov 8 10:05:54 2011 : Debug: status_check = "none"<br>Tue Nov 8 10:05:54 2011 : Debug: ping_interval = 30<br>
Tue Nov 8 10:05:54 2011 : Debug: check_interval = 30<br>Tue Nov 8 10:05:54 2011 : Debug: num_answers_to_alive = 3<br>Tue Nov 8 10:05:54 2011 : Debug: num_pings_to_alive = 3<br>Tue Nov 8 10:05:54 2011 : Debug: revive_interval = 60<br>
Tue Nov 8 10:05:54 2011 : Debug: status_check_timeout = 4<br>Tue Nov 8 10:05:54 2011 : Debug: irt = 2<br>Tue Nov 8 10:05:54 2011 : Debug: mrt = 16<br>Tue Nov 8 10:05:54 2011 : Debug: mrc = 5<br>Tue Nov 8 10:05:54 2011 : Debug: mrd = 30<br>
Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: home_server radius_24 {<br>Tue Nov 8 10:05:54 2011 : Debug: ipaddr = xxx.xxx.xxx.xxx<br>Tue Nov 8 10:05:54 2011 : Debug: port = 1812<br>
Tue Nov 8 10:05:54 2011 : Debug: type = "auth+acct"<br>Tue Nov 8 10:05:54 2011 : Debug: secret = "xxxxxxx"<br>Tue Nov 8 10:05:54 2011 : Debug: response_window = 20<br>Tue Nov 8 10:05:54 2011 : Debug: max_outstanding = 65536<br>
Tue Nov 8 10:05:54 2011 : Debug: zombie_period = 40<br>Tue Nov 8 10:05:54 2011 : Debug: status_check = "none"<br>Tue Nov 8 10:05:54 2011 : Debug: ping_interval = 30<br>Tue Nov 8 10:05:54 2011 : Debug: check_interval = 30<br>
Tue Nov 8 10:05:54 2011 : Debug: num_answers_to_alive = 3<br>Tue Nov 8 10:05:54 2011 : Debug: num_pings_to_alive = 3<br>Tue Nov 8 10:05:54 2011 : Debug: revive_interval = 60<br>Tue Nov 8 10:05:54 2011 : Debug: status_check_timeout = 4<br>
Tue Nov 8 10:05:54 2011 : Debug: irt = 2<br>Tue Nov 8 10:05:54 2011 : Debug: mrt = 16<br>Tue Nov 8 10:05:54 2011 : Debug: mrc = 5<br>Tue Nov 8 10:05:54 2011 : Debug: mrd = 30<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: home_server_pool radius_29 {<br>Tue Nov 8 10:05:54 2011 : Debug: type = fail-over<br>Tue Nov 8 10:05:54 2011 : Debug: home_server = radius_24<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: radiusd: #### Loading Clients ####<br>Tue Nov 8 10:05:54 2011 : Debug: client localhost {<br>Tue Nov 8 10:05:54 2011 : Debug: ipaddr = 127.0.0.1<br>Tue Nov 8 10:05:54 2011 : Debug: require_message_authenticator = no<br>
Tue Nov 8 10:05:54 2011 : Debug: secret = "xxxxxx"<br>Tue Nov 8 10:05:54 2011 : Debug: shortname = "localhost"<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: radiusd: #### Instantiating modules ####<br>
Tue Nov 8 10:05:54 2011 : Debug: instantiate {<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_exec, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_exec<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating exec<br>
Tue Nov 8 10:05:54 2011 : Debug: exec {<br>Tue Nov 8 10:05:54 2011 : Debug: wait = no<br>Tue Nov 8 10:05:54 2011 : Debug: input_pairs = "request"<br>Tue Nov 8 10:05:54 2011 : Debug: shell_escape = yes<br>
Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_expr, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_expr<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating expr<br>
Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_expiration, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_expiration<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating expiration<br>
Tue Nov 8 10:05:54 2011 : Debug: expiration {<br>Tue Nov 8 10:05:54 2011 : Debug: reply-message = "Password Has Expired "<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_logintime, checking if it's valid)<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_logintime<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating logintime<br>Tue Nov 8 10:05:54 2011 : Debug: logintime {<br>Tue Nov 8 10:05:54 2011 : Debug: reply-message = "You are calling outside your allowed timespan "<br>
Tue Nov 8 10:05:54 2011 : Debug: minimum-timeout = 60<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: radiusd: #### Loading Virtual Servers ####<br>
Tue Nov 8 10:05:54 2011 : Debug: server wifi2 {<br>Tue Nov 8 10:05:54 2011 : Debug: modules {<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking authenticate {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_pap, checking if it's valid)<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_pap<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating pap<br>Tue Nov 8 10:05:54 2011 : Debug: pap {<br>Tue Nov 8 10:05:54 2011 : Debug: encryption_scheme = "auto"<br>
Tue Nov 8 10:05:54 2011 : Debug: auto_header = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_unix, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_unix<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating unix<br>Tue Nov 8 10:05:54 2011 : Debug: unix {<br>Tue Nov 8 10:05:54 2011 : Debug: radwtmp = "/usr/local/var/log/radius/radwtmp"<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_ldap, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_ldap<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating ldap<br>
Tue Nov 8 10:05:54 2011 : Debug: ldap {<br>Tue Nov 8 10:05:54 2011 : Debug: server = "xxx.xxx.xxx.xxx"<br>Tue Nov 8 10:05:54 2011 : Debug: port = 389<br>Tue Nov 8 10:05:54 2011 : Debug: password = "xxxxxx"<br>
Tue Nov 8 10:05:54 2011 : Debug: identity = "cn=admin,dc=company,dc=com"<br>Tue Nov 8 10:05:54 2011 : Debug: net_timeout = 50<br>Tue Nov 8 10:05:54 2011 : Debug: timeout = 50<br>Tue Nov 8 10:05:54 2011 : Debug: timelimit = 10<br>
Tue Nov 8 10:05:54 2011 : Debug: tls_mode = no<br>Tue Nov 8 10:05:54 2011 : Debug: start_tls = no<br>Tue Nov 8 10:05:54 2011 : Debug: tls_require_cert = "allow"<br>Tue Nov 8 10:05:54 2011 : Debug: tls {<br>
Tue Nov 8 10:05:54 2011 : Debug: start_tls = no<br>Tue Nov 8 10:05:54 2011 : Debug: require_cert = "allow"<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: basedn = "dc=company,dc=com"<br>
Tue Nov 8 10:05:54 2011 : Debug: filter = "(uid=%{Stripped-User-Name})"<br>Tue Nov 8 10:05:54 2011 : Debug: base_filter = "(objectclass=radiusprofile)"<br>Tue Nov 8 10:05:54 2011 : Debug: password_attribute = "Cleartext-Password"<br>
Tue Nov 8 10:05:54 2011 : Debug: auto_header = no<br>Tue Nov 8 10:05:54 2011 : Debug: access_attr = "uid"<br>Tue Nov 8 10:05:54 2011 : Debug: access_attr_used_for_allow = yes<br>Tue Nov 8 10:05:54 2011 : Debug: groupname_attribute = "cn"<br>
Tue Nov 8 10:05:54 2011 : Debug: groupmembership_filter = "(&(objectClass=groupOfNames)(member=%{control:LDAP-UserDn}))"<br>Tue Nov 8 10:05:54 2011 : Debug: dictionary_mapping = "/usr/local/etc/raddb/ldap.attrmap"<br>
Tue Nov 8 10:05:54 2011 : Debug: ldap_debug = 0<br>Tue Nov 8 10:05:54 2011 : Debug: ldap_connections_number = 200<br>Tue Nov 8 10:05:54 2011 : Debug: compare_check_items = no<br>Tue Nov 8 10:05:54 2011 : Debug: do_xlat = yes<br>
Tue Nov 8 10:05:54 2011 : Debug: set_auth_type = yes<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: Registering ldap_groupcmp for Ldap-Group<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: Registering ldap_xlat with xlat_name ldap<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: reading ldap<->radius mappings from file /usr/local/etc/raddb/ldap.attrmap<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP sambaLmPassword mapped to RADIUS LM-Password<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP sambaNtPassword mapped to RADIUS NT-Password<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusNASIpAddress mapped to RADIUS NAS-IP-Address<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusClass mapped to RADIUS Class<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS Framed-AppleTalk-Link<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS Framed-AppleTalk-Network<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS Framed-AppleTalk-Zone<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusReplyMessage mapped to RADIUS Reply-Message<br>
Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusTunnelType mapped to RADIUS Tunnel-Type<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusTunnelMediumType mapped to RADIUS Tunnel-Medium-Type<br>Tue Nov 8 10:05:54 2011 : Debug: rlm_ldap: LDAP radiusTunnelPrivateGroupId mapped to RADIUS Tunnel-Private-Group-Id<br>
Tue Nov 8 10:05:54 2011 : Debug: conns: 0x8174a38<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_eap, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_eap<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap<br>
Tue Nov 8 10:05:54 2011 : Debug: eap {<br>Tue Nov 8 10:05:54 2011 : Debug: default_eap_type = "tls"<br>Tue Nov 8 10:05:54 2011 : Debug: timer_expire = 60<br>Tue Nov 8 10:05:54 2011 : Debug: ignore_unknown_eap_types = no<br>
Tue Nov 8 10:05:54 2011 : Debug: cisco_accounting_username_bug = no<br>Tue Nov 8 10:05:54 2011 : Debug: max_sessions = 2048<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to sub-module rlm_eap_md5<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap-md5<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to sub-module rlm_eap_leap<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap-leap<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to sub-module rlm_eap_gtc<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap-gtc<br>Tue Nov 8 10:05:54 2011 : Debug: gtc {<br>Tue Nov 8 10:05:54 2011 : Debug: challenge = "Password: "<br>Tue Nov 8 10:05:54 2011 : Debug: auth_type = "PAP"<br>
Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to sub-module rlm_eap_tls<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap-tls<br>Tue Nov 8 10:05:54 2011 : Debug: tls {<br>
Tue Nov 8 10:05:54 2011 : Debug: rsa_key_exchange = no<br>Tue Nov 8 10:05:54 2011 : Debug: dh_key_exchange = yes<br>Tue Nov 8 10:05:54 2011 : Debug: rsa_key_length = 512<br>Tue Nov 8 10:05:54 2011 : Debug: dh_key_length = 512<br>
Tue Nov 8 10:05:54 2011 : Debug: verify_depth = 0<br>Tue Nov 8 10:05:54 2011 : Debug: pem_file_type = yes<br>Tue Nov 8 10:05:54 2011 : Debug: private_key_file = "/usr/local/etc/raddb/eduroam_CA/10076494/radius.es_privatekey.pem"<br>
Tue Nov 8 10:05:54 2011 : Debug: certificate_file = "/usr/local/etc/raddb/eduroam_CA/10076494/10076494.crt"<br>Tue Nov 8 10:05:54 2011 : Debug: CA_file = "/usr/local/etc/raddb/eduroam_CA/10076494/TERENASSL_PATH.pem"<br>
Tue Nov 8 10:05:54 2011 : Debug: dh_file = "/usr/local/etc/raddb/eduroam_CA/10076494/dh"<br>Tue Nov 8 10:05:54 2011 : Debug: random_file = "/usr/local/etc/raddb/eduroam_CA/10076494/random"<br>
Tue Nov 8 10:05:54 2011 : Debug: fragment_size = 1024<br>Tue Nov 8 10:05:54 2011 : Debug: include_length = yes<br>Tue Nov 8 10:05:54 2011 : Debug: check_crl = no<br>Tue Nov 8 10:05:54 2011 : Debug: cipher_list = "DEFAULT"<br>
Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to sub-module rlm_eap_ttls<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap-ttls<br>Tue Nov 8 10:05:54 2011 : Debug: ttls {<br>
Tue Nov 8 10:05:54 2011 : Debug: default_eap_type = "md5"<br>Tue Nov 8 10:05:54 2011 : Debug: copy_request_to_tunnel = no<br>Tue Nov 8 10:05:54 2011 : Debug: use_tunneled_reply = yes<br>Tue Nov 8 10:05:54 2011 : Debug: virtual_server = "wifi2"<br>
Tue Nov 8 10:05:54 2011 : Debug: include_length = yes<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to sub-module rlm_eap_peap<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap-peap<br>
Tue Nov 8 10:05:54 2011 : Debug: peap {<br>Tue Nov 8 10:05:54 2011 : Debug: default_eap_type = "mschapv2"<br>Tue Nov 8 10:05:54 2011 : Debug: copy_request_to_tunnel = no<br>Tue Nov 8 10:05:54 2011 : Debug: use_tunneled_reply = yes<br>
Tue Nov 8 10:05:54 2011 : Debug: proxy_tunneled_request_as_eap = yes<br>Tue Nov 8 10:05:54 2011 : Debug: virtual_server = "inner-tunnel"<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to sub-module rlm_eap_mschapv2<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating eap-mschapv2<br>Tue Nov 8 10:05:54 2011 : Debug: mschapv2 {<br>Tue Nov 8 10:05:54 2011 : Debug: with_ntdomain_hack = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Checking authorize {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_detail, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_detail<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating auth_log<br>Tue Nov 8 10:05:54 2011 : Debug: detail auth_log {<br>Tue Nov 8 10:05:54 2011 : Debug: detailfile = "/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d"<br>
Tue Nov 8 10:05:54 2011 : Debug: header = "%t"<br>Tue Nov 8 10:05:54 2011 : Debug: detailperm = 384<br>Tue Nov 8 10:05:54 2011 : Debug: dirperm = 493<br>Tue Nov 8 10:05:54 2011 : Debug: locking = no<br>
Tue Nov 8 10:05:54 2011 : Debug: log_packet_header = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_preprocess, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_preprocess<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating preprocess<br>Tue Nov 8 10:05:54 2011 : Debug: preprocess {<br>Tue Nov 8 10:05:54 2011 : Debug: huntgroups = "/usr/local/etc/raddb/huntgroups"<br>
Tue Nov 8 10:05:54 2011 : Debug: hints = "/usr/local/etc/raddb/hints"<br>Tue Nov 8 10:05:54 2011 : Debug: with_ascend_hack = no<br>Tue Nov 8 10:05:54 2011 : Debug: ascend_channels_per_line = 23<br>
Tue Nov 8 10:05:54 2011 : Debug: with_ntdomain_hack = no<br>Tue Nov 8 10:05:54 2011 : Debug: with_specialix_jetstream_hack = no<br>Tue Nov 8 10:05:54 2011 : Debug: with_cisco_vsa_hack = no<br>Tue Nov 8 10:05:54 2011 : Debug: with_alvarion_vsa_hack = no<br>
Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_realm, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_realm<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating suffix<br>
Tue Nov 8 10:05:54 2011 : Debug: realm suffix {<br>Tue Nov 8 10:05:54 2011 : Debug: format = "suffix"<br>Tue Nov 8 10:05:54 2011 : Debug: delimiter = "@"<br>Tue Nov 8 10:05:54 2011 : Debug: ignore_default = no<br>
Tue Nov 8 10:05:54 2011 : Debug: ignore_null = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_files, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_files<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating files<br>Tue Nov 8 10:05:54 2011 : Debug: files {<br>Tue Nov 8 10:05:54 2011 : Debug: usersfile = "/usr/local/etc/raddb/users"<br>Tue Nov 8 10:05:54 2011 : Debug: acctusersfile = "/usr/local/etc/raddb/acct_users"<br>
Tue Nov 8 10:05:54 2011 : Debug: preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"<br>Tue Nov 8 10:05:54 2011 : Debug: compat = "no"<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking preacct {...} for more modules to load<br>
Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_acct_unique, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_acct_unique<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating acct_unique<br>
Tue Nov 8 10:05:54 2011 : Debug: acct_unique {<br>Tue Nov 8 10:05:54 2011 : Debug: key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Checking accounting {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating detail<br>Tue Nov 8 10:05:54 2011 : Debug: detail {<br>Tue Nov 8 10:05:54 2011 : Debug: detailfile = "/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"<br>
Tue Nov 8 10:05:54 2011 : Debug: header = "%t"<br>Tue Nov 8 10:05:54 2011 : Debug: detailperm = 384<br>Tue Nov 8 10:05:54 2011 : Debug: dirperm = 493<br>Tue Nov 8 10:05:54 2011 : Debug: locking = no<br>
Tue Nov 8 10:05:54 2011 : Debug: log_packet_header = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_always, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_always<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating ok<br>Tue Nov 8 10:05:54 2011 : Debug: always ok {<br>Tue Nov 8 10:05:54 2011 : Debug: rcode = "ok"<br>Tue Nov 8 10:05:54 2011 : Debug: simulcount = 0<br>
Tue Nov 8 10:05:54 2011 : Debug: mpp = no<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking session {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_radutmp, checking if it's valid)<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_radutmp<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating radutmp<br>Tue Nov 8 10:05:54 2011 : Debug: radutmp {<br>Tue Nov 8 10:05:54 2011 : Debug: filename = "/usr/local/var/log/radius/radutmp"<br>
Tue Nov 8 10:05:54 2011 : Debug: username = "%{User-Name}"<br>Tue Nov 8 10:05:54 2011 : Debug: case_sensitive = yes<br>Tue Nov 8 10:05:54 2011 : Debug: check_with_nas = yes<br>Tue Nov 8 10:05:54 2011 : Debug: perm = 384<br>
Tue Nov 8 10:05:54 2011 : Debug: callerid = yes<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking post-proxy {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking post-auth {...} for more modules to load<br>
Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_attr_filter, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_attr_filter<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating attr_filter.access_reject<br>
Tue Nov 8 10:05:54 2011 : Debug: attr_filter attr_filter.access_reject {<br>Tue Nov 8 10:05:54 2011 : Debug: attrsfile = "/usr/local/etc/raddb/attrs.access_reject"<br>Tue Nov 8 10:05:54 2011 : Debug: key = "%{User-Name}"<br>
Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: } # modules<br>Tue Nov 8 10:05:54 2011 : Debug: } # server<br>Tue Nov 8 10:05:54 2011 : Debug: server inner-tunnel {<br>Tue Nov 8 10:05:54 2011 : Debug: modules {<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Checking authenticate {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_chap, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_chap<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating chap<br>Tue Nov 8 10:05:54 2011 : Debug: (Loaded rlm_mschap, checking if it's valid)<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Linked to module rlm_mschap<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating mschap<br>Tue Nov 8 10:05:54 2011 : Debug: mschap {<br>Tue Nov 8 10:05:54 2011 : Debug: use_mppe = yes<br>Tue Nov 8 10:05:54 2011 : Debug: require_encryption = no<br>
Tue Nov 8 10:05:54 2011 : Debug: require_strong = no<br>Tue Nov 8 10:05:54 2011 : Debug: with_ntdomain_hack = yes<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking authorize {...} for more modules to load<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Checking session {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking post-proxy {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking post-auth {...} for more modules to load<br>
Tue Nov 8 10:05:54 2011 : Debug: } # modules<br>Tue Nov 8 10:05:54 2011 : Debug: } # server<br>Tue Nov 8 10:05:54 2011 : Debug: server {<br>Tue Nov 8 10:05:54 2011 : Debug: modules {<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking authenticate {...} for more modules to load<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Checking authorize {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking preacct {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking accounting {...} for more modules to load<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Instantiating attr_filter.accounting_response<br>Tue Nov 8 10:05:54 2011 : Debug: attr_filter attr_filter.accounting_response {<br>Tue Nov 8 10:05:54 2011 : Debug: attrsfile = "/usr/local/etc/raddb/attrs.accounting_response"<br>
Tue Nov 8 10:05:54 2011 : Debug: key = "%{User-Name}"<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking session {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: Module: Checking post-proxy {...} for more modules to load<br>
Tue Nov 8 10:05:54 2011 : Debug: Module: Checking post-auth {...} for more modules to load<br>Tue Nov 8 10:05:54 2011 : Debug: } # modules<br>Tue Nov 8 10:05:54 2011 : Debug: } # server<br>Tue Nov 8 10:05:54 2011 : Debug: radiusd: #### Opening IP addresses and Ports ####<br>
Tue Nov 8 10:05:54 2011 : Debug: listen {<br>Tue Nov 8 10:05:54 2011 : Debug: type = "auth"<br>Tue Nov 8 10:05:54 2011 : Debug: ipaddr = *<br>Tue Nov 8 10:05:54 2011 : Debug: port = 0<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: listen {<br>Tue Nov 8 10:05:54 2011 : Debug: type = "acct"<br>Tue Nov 8 10:05:54 2011 : Debug: ipaddr = *<br>Tue Nov 8 10:05:54 2011 : Debug: port = 0<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>
Tue Nov 8 10:05:54 2011 : Debug: listen {<br>Tue Nov 8 10:05:54 2011 : Debug: type = "control"<br>Tue Nov 8 10:05:54 2011 : Debug: listen {<br>Tue Nov 8 10:05:54 2011 : Debug: socket = "/usr/local/var/run/radiusd/radiusd.sock"<br>
Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: }<br>Tue Nov 8 10:05:54 2011 : Debug: Listening on authentication address * port 1812<br>Tue Nov 8 10:05:54 2011 : Debug: Listening on accounting address * port 1813<br>
Tue Nov 8 10:05:54 2011 : Debug: Listening on command file /usr/local/var/run/radiusd/radiusd.sock<br>Tue Nov 8 10:05:54 2011 : Debug: Listening on proxy address * port 1814<br>Tue Nov 8 10:05:54 2011 : Debug: Ready to process requests.<br>
rad_recv: Access-Request packet from host xxx.xxx.xxx.xxx port 32928, id=80, length=61<br> User-Name = "student13"<br> User-Password = "xxxxxxx"<br> NAS-IP-Address = xxx.xxx.xxx.xxx<br> NAS-Port = 10<br>
Tue Nov 8 10:06:21 2011 : Info: +- entering group authorize {...}<br>Tue Nov 8 10:06:21 2011 : Info: ++[preprocess] returns ok<br>Tue Nov 8 10:06:21 2011 : Info: ++[chap] returns noop<br>Tue Nov 8 10:06:21 2011 : Info: ++[mschap] returns noop<br>
Tue Nov 8 10:06:21 2011 : Info: [suffix] No '@' in User-Name = "student13", looking up realm NULL<br>Tue Nov 8 10:06:21 2011 : Info: [suffix] Found realm "NULL"<br>Tue Nov 8 10:06:21 2011 : Info: [suffix] Adding Stripped-User-Name = "student13"<br>
Tue Nov 8 10:06:21 2011 : Info: [suffix] Adding Realm = "NULL"<br>Tue Nov 8 10:06:21 2011 : Info: [suffix] Authentication realm is LOCAL.<br>Tue Nov 8 10:06:21 2011 : Info: ++[suffix] returns ok<br>Tue Nov 8 10:06:21 2011 : Info: [eap] No EAP-Message, not doing EAP<br>
Tue Nov 8 10:06:21 2011 : Info: ++[eap] returns noop<br>Tue Nov 8 10:06:21 2011 : Info: ++[unix] returns notfound<br>Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: Entering ldap_groupcmp()<br>Tue Nov 8 10:06:21 2011 : Info: [files] expand: dc=company,dc=com -> dc=company,dc=com<br>
Tue Nov 8 10:06:21 2011 : Info: [files] expand: (uid=%{Stripped-User-Name}) -> (uid=student13)<br>Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: ldap_get_conn: Checking Id: 0<br>Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: ldap_get_conn: Got Id: 0<br>
Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: attempting LDAP reconnection<br>Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: (re)connect to xxx.xxx.xxx.xxx:389, authentication 0<br>Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: bind as cn=xxxxx,dc=company,dc=com/pppp to xxx.xxx.xxx.xxx:389<br>
Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: waiting for bind result ...<br>Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: Bind was successful<br>Tue Nov 8 10:06:21 2011 : Debug: rlm_ldap: performing search in dc=company,dc=com, with filter (uid=student13)<br>
Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: ldap_release_conn: Release Id: 0<br>Tue Nov 8 10:06:22 2011 : Info: [files] expand: (&(objectClass=groupOfNames)(member=%{control:LDAP-UserDn})) -> (&(objectClass=groupOfNames)(member=cn\3dstudent13\2ccn\3dusers\2cdc\3dcompany\2cdc\3dcom))<br>
Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: ldap_get_conn: Checking Id: 0<br>Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: ldap_get_conn: Got Id: 0<br>Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: performing search in dc=company,dc=com, with filter<br>
(&(cn=student13)(&(objectClass=groupOfNames)(member=cn\3dstudent13\2ccn\3dusers\2cdc\3dcompany\2cdc\3dcom)))<br>Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap::ldap_groupcmp: User found in group student<br>Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: ldap_release_conn: Release Id: 0<br>
Tue Nov 8 10:06:22 2011 : Info: [files] users: Matched entry DEFAULT at line 5<br>Tue Nov 8 10:06:22 2011 : Info: ++[files] returns ok<br>Tue Nov 8 10:06:22 2011 : Info: [ldap] performing user authorization for student13<br>
Tue Nov 8 10:06:22 2011 : Info: [ldap] expand: (uid=%{Stripped-User-Name}) -> (uid=student13)<br>Tue Nov 8 10:06:22 2011 : Info: [ldap] expand: dc=company,dc=com -> dc=company,dc=com<br>Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: ldap_get_conn: Checking Id: 0<br>
Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: ldap_get_conn: Got Id: 0<br>Tue Nov 8 10:06:22 2011 : Debug: rlm_ldap: performing search in dc=company,dc=com, with filter (uid=student13)<br>Tue Nov 8 10:06:23 2011 : Info: [ldap] checking if remote access for student13 is allowed by uid<br>
Tue Nov 8 10:06:23 2011 : Info: [ldap] looking for check items in directory...<br>Tue Nov 8 10:06:23 2011 : Debug: rlm_ldap: sambaNtPassword -> NT-Password == 0x555463443354631374445413844464334304134353244313042453044433445<br>
Tue Nov 8 10:06:23 2011 : Debug: rlm_ldap: sambaLmPassword -> LM-Password == 0x4323314245313638353037463743464341414433423433354235313430344545<br>Tue Nov 8 10:06:23 2011 : Info: [ldap] looking for reply items in directory...<br>
Tue Nov 8 10:06:23 2011 : Debug: WARNING: No "known good" password was found in LDAP. Are you sure that the user is configured correctly?<br>Tue Nov 8 10:06:23 2011 : Info: [ldap] Setting Auth-Type = LDAP<br>
Tue Nov 8 10:06:23 2011 : Info: [ldap] user student13 authorized to use remote access<br>Tue Nov 8 10:06:23 2011 : Debug: rlm_ldap: ldap_release_conn: Release Id: 0<br>Tue Nov 8 10:06:23 2011 : Info: ++[ldap] returns ok<br>
Tue Nov 8 10:06:23 2011 : Info: ++[expiration] returns noop<br>Tue Nov 8 10:06:23 2011 : Info: ++[logintime] returns noop<br>Tue Nov 8 10:06:23 2011 : Info: [pap] Normalizing NT-Password from hex encoding<br>Tue Nov 8 10:06:23 2011 : Info: [pap] Normalizing LM-Password from hex encoding<br>
Tue Nov 8 10:06:23 2011 : Info: [pap] Found existing Auth-Type, not changing it.<br>Tue Nov 8 10:06:23 2011 : Info: ++[pap] returns noop<br>Tue Nov 8 10:06:23 2011 : Info: Found Auth-Type = LDAP<br>Tue Nov 8 10:06:23 2011 : Info: +- entering group LDAP {...}<br>
Tue Nov 8 10:06:23 2011 : Info: [ldap] login attempt by "student13" with password "xxxxxx"<br>Tue Nov 8 10:06:23 2011 : Info: [ldap] user DN: cn=student13,cn=users,dc=company,dc=com<br>Tue Nov 8 10:06:23 2011 : Debug: rlm_ldap: (re)connect to xxx.xxx.xxx.xxx:389, authentication 1<br>
Tue Nov 8 10:06:23 2011 : Debug: rlm_ldap: bind as cn=student13,cn=users,dc=company,dc=com/xxxxxx to xxx.xxx.xxx.xxx:389<br>Tue Nov 8 10:06:23 2011 : Debug: rlm_ldap: waiting for bind result ...<br>Tue Nov 8 10:06:23 2011 : Debug: rlm_ldap: Bind was successful<br>
Tue Nov 8 10:06:23 2011 : Info: [ldap] user student13 authenticated succesfully<br>Tue Nov 8 10:06:23 2011 : Info: ++[ldap] returns ok<br>Tue Nov 8 10:06:23 2011 : Auth: Login OK: [student13] (from client radius port 10)<br>
Tue Nov 8 10:06:23 2011 : Info: +- entering group post-auth {...}<br>Tue Nov 8 10:06:23 2011 : Info: ++[exec] returns noop<br>Sending Access-Accept of id 80 to xxx.xxx.xxx.xxx port 32928<br> Aruba-User-Role = "student"<br>
Tue Nov 8 10:06:23 2011 : Info: Finished request 0.<br>Tue Nov 8 10:06:23 2011 : Debug: Going to the next request<br>Tue Nov 8 10:06:23 2011 : Debug: Waking up in 4.9 seconds.<br>Tue Nov 8 10:06:28 2011 : Info: Cleaning up request 0 ID 80 with timestamp +27<br>
Tue Nov 8 10:06:28 2011 : Debug: Ready to process requests.<br><br>Thank you in advance<br>Remon<br><br>