Sorry I use another account for this reply.<br><br>root@ubuntu:/usr/local/etc/raddb# radeapclient -x localhost auth testing123 </home/fang/freeradius-server-2.1.9/src/tests/eapmd5-01/req.txt<br><br>+++> About to send encoded packet:<br> User-Name = "test"<br> Cleartext-Password = "test"<br> NAS-IP-Address = 209.87.252.247<br> EAP-Code = Response<br> EAP-Id = 210<br> EAP-Type-Identity = "test"<br> Message-Authenticator = 0x00<br> NAS-Port = 0<br><+++ EAP decoded packet: (notice this is the accept-challenge packet and Framed-MTU = 1500 is there)<br> Reply-Message = "Hello, test"<br> Framed-MTU = 1500<br> EAP-Message = 0x01d3001604108bb813bc0db605897f7ecea4036ca422<br> Message-Authenticator = 0x62cb659c92b12b930fda931c6ba04af7<br> State = 0xbbd9622dbb0a661e27e315270e22da7a<br> EAP-Id = 211<br> EAP-Code = Request<br> EAP-Type-MD5 = 0x108bb813bc0db605897f7ecea4036ca422<br><br>+++> About to send encoded packet:<br> User-Name = "test"<br> Cleartext-Password = "test"<br> NAS-IP-Address = 209.87.252.247<br> EAP-Code = Response<br> EAP-Id = 211<br> Message-Authenticator = 0x00000000000000000000000000000000<br> NAS-Port = 0<br> EAP-Type-MD5 = 0x1092ee897591f29bbfde36e49152e39501<br> State = 0xbbd9622dbb0a661e27e315270e22da7a<br><+++ EAP decoded packet:<br> Reply-Message = "Hello, test"<br> Framed-MTU = 1500<br> EAP-Message = 0x03d30004<br> Message-Authenticator = 0xa7de7cd1e3a2025447c8a0bc17ec4c24<br> User-Name = "test"<br> EAP-Id = 211<br> EAP-Code = Success<br><br><br><br><br>I use radiusd -xxX to open debug, and to verify the attr_filter module running process, I add the<br><br>printf("***** %s\n", __FUNC__); in every function of rlm_attr_filter.c<br><br>so you can notice something like:<br><br>****** attr_filter_instantiate ****** getattrsfile <br><br>I think if the filter is trying to filter something, the function attr_filter_common should be executed, but not actually.<br><br>I don't know why? Since I am a new user and there is no relevant examples online, can you give me more hints, but not just a few words? Although I know your time may be very limitted. <br><br>root@ubuntu:/home/fang/freeradius-server-2.1.9/src/modules/rlm_attr_filter# radiusd -xxX<br>Tue May 25 22:05:03 2010 : Info: FreeRADIUS Version 2.1.9, for host i686-pc-linux-gnu, built on May 25 2010 at 18:16:56<br>Tue May 25 22:05:03 2010 : Info: Copyright (C) 1999-2009 The FreeRADIUS server project and contributors. <br>Tue May 25 22:05:03 2010 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A <br>Tue May 25 22:05:03 2010 : Info: PARTICULAR PURPOSE. <br>Tue May 25 22:05:03 2010 : Info: You may redistribute copies of FreeRADIUS under the terms of the <br>Tue May 25 22:05:03 2010 : Info: GNU General Public License v2. <br>Tue May 25 22:05:03 2010 : Info: Starting - reading configuration files ...<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/radiusd.conf<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/proxy.conf<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/clients.conf<br>Tue May 25 22:05:03 2010 : Debug: including files in directory /usr/local/etc/raddb/modules/<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/acct_unique<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/detail.example.com<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/logintime<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/etc_group<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/realm<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/ntlm_auth<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/krb5<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/sqlcounter_expire_on_login<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/passwd<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/counter<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/detail.log<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/linelog<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/unix<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/smsotp<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/chap<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/policy<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/radutmp<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/always<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/exec<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/otp<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/detail<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/cui<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2vlan<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2ip<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/ldap<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/ippool<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_rewrite<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/expr<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/preprocess<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/digest<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/checkval<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/wimax<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/pap<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_filter<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/sradutmp<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/inner-eap<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/echo<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/mschap<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/sql_log<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/smbpasswd<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/expiration<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/pam<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/files<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/perl<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/eap.conf<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/policy.conf<br>Tue May 25 22:05:03 2010 : Debug: including files in directory /usr/local/etc/raddb/sites-enabled/<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/control-socket<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel<br>Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/default<br>Tue May 25 22:05:03 2010 : Debug: main {<br>Tue May 25 22:05:03 2010 : Debug: allow_core_dumps = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: including dictionary file /usr/local/etc/raddb/dictionary<br>Tue May 25 22:05:03 2010 : Debug: main {<br>Tue May 25 22:05:03 2010 : Debug: prefix = "/usr/local"<br>Tue May 25 22:05:03 2010 : Debug: localstatedir = "/usr/local/var"<br>Tue May 25 22:05:03 2010 : Debug: logdir = "/usr/local/var/log/radius"<br>Tue May 25 22:05:03 2010 : Debug: libdir = "/usr/local/lib"<br>Tue May 25 22:05:03 2010 : Debug: radacctdir = "/usr/local/var/log/radius/radacct"<br>Tue May 25 22:05:03 2010 : Debug: hostname_lookups = no<br>Tue May 25 22:05:03 2010 : Debug: max_request_time = 30<br>Tue May 25 22:05:03 2010 : Debug: cleanup_delay = 5<br>Tue May 25 22:05:03 2010 : Debug: max_requests = 1024<br>Tue May 25 22:05:03 2010 : Debug: pidfile = "/usr/local/var/run/radiusd/radiusd.pid"<br>Tue May 25 22:05:03 2010 : Debug: checkrad = "/usr/local/sbin/checkrad"<br>Tue May 25 22:05:03 2010 : Debug: debug_level = 0<br>Tue May 25 22:05:03 2010 : Debug: proxy_requests = yes<br>Tue May 25 22:05:03 2010 : Debug: log {<br>Tue May 25 22:05:03 2010 : Debug: stripped_names = no<br>Tue May 25 22:05:03 2010 : Debug: auth = no<br>Tue May 25 22:05:03 2010 : Debug: auth_badpass = no<br>Tue May 25 22:05:03 2010 : Debug: auth_goodpass = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: security {<br>Tue May 25 22:05:03 2010 : Debug: max_attributes = 200<br>Tue May 25 22:05:03 2010 : Debug: reject_delay = 1<br>Tue May 25 22:05:03 2010 : Debug: status_server = yes<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: radiusd: #### Loading Realms and Home Servers ####<br>Tue May 25 22:05:03 2010 : Debug: proxy server {<br>Tue May 25 22:05:03 2010 : Debug: retry_delay = 5<br>Tue May 25 22:05:03 2010 : Debug: retry_count = 3<br>Tue May 25 22:05:03 2010 : Debug: default_fallback = no<br>Tue May 25 22:05:03 2010 : Debug: dead_time = 120<br>Tue May 25 22:05:03 2010 : Debug: wake_all_if_all_dead = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: home_server localhost {<br>Tue May 25 22:05:03 2010 : Debug: ipaddr = 127.0.0.1<br>Tue May 25 22:05:03 2010 : Debug: port = 1812<br>Tue May 25 22:05:03 2010 : Debug: type = "auth"<br>Tue May 25 22:05:03 2010 : Debug: secret = "testing123"<br>Tue May 25 22:05:03 2010 : Debug: response_window = 20<br>Tue May 25 22:05:03 2010 : Debug: max_outstanding = 65536<br>Tue May 25 22:05:03 2010 : Debug: require_message_authenticator = no<br>Tue May 25 22:05:03 2010 : Debug: zombie_period = 40<br>Tue May 25 22:05:03 2010 : Debug: status_check = "status-server"<br>Tue May 25 22:05:03 2010 : Debug: ping_interval = 30<br>Tue May 25 22:05:03 2010 : Debug: check_interval = 30<br>Tue May 25 22:05:03 2010 : Debug: num_answers_to_alive = 3<br>Tue May 25 22:05:03 2010 : Debug: num_pings_to_alive = 3<br>Tue May 25 22:05:03 2010 : Debug: revive_interval = 120<br>Tue May 25 22:05:03 2010 : Debug: status_check_timeout = 4<br>Tue May 25 22:05:03 2010 : Debug: irt = 2<br>Tue May 25 22:05:03 2010 : Debug: mrt = 16<br>Tue May 25 22:05:03 2010 : Debug: mrc = 5<br>Tue May 25 22:05:03 2010 : Debug: mrd = 30<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: home_server_pool my_auth_failover {<br>Tue May 25 22:05:03 2010 : Debug: type = fail-over<br>Tue May 25 22:05:03 2010 : Debug: home_server = localhost<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: realm example.com {<br>Tue May 25 22:05:03 2010 : Debug: auth_pool = my_auth_failover<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: realm LOCAL {<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: radiusd: #### Loading Clients ####<br>Tue May 25 22:05:03 2010 : Debug: client localhost {<br>Tue May 25 22:05:03 2010 : Debug: ipaddr = 127.0.0.1<br>Tue May 25 22:05:03 2010 : Debug: require_message_authenticator = no<br>Tue May 25 22:05:03 2010 : Debug: secret = "testing123"<br>Tue May 25 22:05:03 2010 : Debug: nastype = "other"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: radiusd: #### Instantiating modules ####<br>Tue May 25 22:05:03 2010 : Debug: instantiate {<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_exec, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_exec<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating exec<br>Tue May 25 22:05:03 2010 : Debug: exec {<br>Tue May 25 22:05:03 2010 : Debug: wait = no<br>Tue May 25 22:05:03 2010 : Debug: input_pairs = "request"<br>Tue May 25 22:05:03 2010 : Debug: shell_escape = yes<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_expr, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_expr<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating expr<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_expiration, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_expiration<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating expiration<br>Tue May 25 22:05:03 2010 : Debug: expiration {<br>Tue May 25 22:05:03 2010 : Debug: reply-message = "Password Has Expired "<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_logintime, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_logintime<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating logintime<br>Tue May 25 22:05:03 2010 : Debug: logintime {<br>Tue May 25 22:05:03 2010 : Debug: reply-message = "You are calling outside your allowed timespan "<br>Tue May 25 22:05:03 2010 : Debug: minimum-timeout = 60<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: radiusd: #### Loading Virtual Servers ####<br>Tue May 25 22:05:03 2010 : Debug: server inner-tunnel {<br>Tue May 25 22:05:03 2010 : Debug: modules {<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking authenticate {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_pap, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_pap<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating pap<br>Tue May 25 22:05:03 2010 : Debug: pap {<br>Tue May 25 22:05:03 2010 : Debug: encryption_scheme = "auto"<br>Tue May 25 22:05:03 2010 : Debug: auto_header = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_chap, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_chap<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating chap<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_mschap, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_mschap<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating mschap<br>Tue May 25 22:05:03 2010 : Debug: mschap {<br>Tue May 25 22:05:03 2010 : Debug: use_mppe = yes<br>Tue May 25 22:05:03 2010 : Debug: require_encryption = no<br>Tue May 25 22:05:03 2010 : Debug: require_strong = no<br>Tue May 25 22:05:03 2010 : Debug: with_ntdomain_hack = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_unix, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_unix<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating unix<br>Tue May 25 22:05:03 2010 : Debug: unix {<br>Tue May 25 22:05:03 2010 : Debug: radwtmp = "/usr/local/var/log/radius/radwtmp"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_eap, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_eap<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating eap<br>Tue May 25 22:05:03 2010 : Debug: eap {<br>Tue May 25 22:05:03 2010 : Debug: default_eap_type = "md5"<br>Tue May 25 22:05:03 2010 : Debug: timer_expire = 60<br>Tue May 25 22:05:03 2010 : Debug: ignore_unknown_eap_types = no<br>Tue May 25 22:05:03 2010 : Debug: cisco_accounting_username_bug = no<br>Tue May 25 22:05:03 2010 : Debug: max_sessions = 4096<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to sub-module rlm_eap_md5<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating eap-md5<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to sub-module rlm_eap_leap<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating eap-leap<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to sub-module rlm_eap_gtc<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating eap-gtc<br>Tue May 25 22:05:03 2010 : Debug: gtc {<br>Tue May 25 22:05:03 2010 : Debug: challenge = "Password: "<br>Tue May 25 22:05:03 2010 : Debug: auth_type = "PAP"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Ignoring EAP-Type/tls because we do not have OpenSSL support.<br>Tue May 25 22:05:03 2010 : Debug: Ignoring EAP-Type/ttls because we do not have OpenSSL support.<br>Tue May 25 22:05:03 2010 : Debug: Ignoring EAP-Type/peap because we do not have OpenSSL support.<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to sub-module rlm_eap_mschapv2<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating eap-mschapv2<br>Tue May 25 22:05:03 2010 : Debug: mschapv2 {<br>Tue May 25 22:05:03 2010 : Debug: with_ntdomain_hack = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking authorize {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_realm, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_realm<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating suffix<br>Tue May 25 22:05:03 2010 : Debug: realm suffix {<br>Tue May 25 22:05:03 2010 : Debug: format = "suffix"<br>Tue May 25 22:05:03 2010 : Debug: delimiter = "@"<br>Tue May 25 22:05:03 2010 : Debug: ignore_default = no<br>Tue May 25 22:05:03 2010 : Debug: ignore_null = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_files, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_files<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating files<br>Tue May 25 22:05:03 2010 : Debug: files {<br>Tue May 25 22:05:03 2010 : Debug: usersfile = "/usr/local/etc/raddb/users"<br>Tue May 25 22:05:03 2010 : Debug: acctusersfile = "/usr/local/etc/raddb/acct_users"<br>Tue May 25 22:05:03 2010 : Debug: preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"<br>Tue May 25 22:05:03 2010 : Debug: compat = "no"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking session {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_radutmp, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_radutmp<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating radutmp<br>Tue May 25 22:05:03 2010 : Debug: radutmp {<br>Tue May 25 22:05:03 2010 : Debug: filename = "/usr/local/var/log/radius/radutmp"<br>Tue May 25 22:05:03 2010 : Debug: username = "%{User-Name}"<br>Tue May 25 22:05:03 2010 : Debug: case_sensitive = yes<br>Tue May 25 22:05:03 2010 : Debug: check_with_nas = yes<br>Tue May 25 22:05:03 2010 : Debug: perm = 384<br>Tue May 25 22:05:03 2010 : Debug: callerid = yes<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking post-proxy {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking post-auth {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_attr_filter, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_attr_filter<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating attr_filter.access_reject<br>Tue May 25 22:05:03 2010 : Debug: ****** attr_filter_instantiate <br>Tue May 25 22:05:03 2010 : Debug: attr_filter attr_filter.access_reject {<br>Tue May 25 22:05:03 2010 : Debug: attrsfile = "/usr/local/etc/raddb/attrs.access_reject"<br>Tue May 25 22:05:03 2010 : Debug: key = "%{User-Name}"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: ****** getattrsfile <br>Tue May 25 22:05:03 2010 : Debug: } # modules<br>Tue May 25 22:05:03 2010 : Debug: } # server<br>Tue May 25 22:05:03 2010 : Debug: server {<br>Tue May 25 22:05:03 2010 : Debug: modules {<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking authenticate {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating attr_filter.access_challenge<br>Tue May 25 22:05:03 2010 : Debug: ****** attr_filter_instantiate <br>Tue May 25 22:05:03 2010 : Debug: attr_filter attr_filter.access_challenge {<br>Tue May 25 22:05:03 2010 : Debug: attrsfile = "/usr/local/etc/raddb/attrs.access_challenge"<br>Tue May 25 22:05:03 2010 : Debug: key = "%{User-Name}"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: ****** getattrsfile <br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_always, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_always<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating handled<br>Tue May 25 22:05:03 2010 : Debug: always handled {<br>Tue May 25 22:05:03 2010 : Debug: rcode = "handled"<br>Tue May 25 22:05:03 2010 : Debug: simulcount = 0<br>Tue May 25 22:05:03 2010 : Debug: mpp = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking authorize {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_preprocess, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_preprocess<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating preprocess<br>Tue May 25 22:05:03 2010 : Debug: preprocess {<br>Tue May 25 22:05:03 2010 : Debug: huntgroups = "/usr/local/etc/raddb/huntgroups"<br>Tue May 25 22:05:03 2010 : Debug: hints = "/usr/local/etc/raddb/hints"<br>Tue May 25 22:05:03 2010 : Debug: with_ascend_hack = no<br>Tue May 25 22:05:03 2010 : Debug: ascend_channels_per_line = 23<br>Tue May 25 22:05:03 2010 : Debug: with_ntdomain_hack = no<br>Tue May 25 22:05:03 2010 : Debug: with_specialix_jetstream_hack = no<br>Tue May 25 22:05:03 2010 : Debug: with_cisco_vsa_hack = no<br>Tue May 25 22:05:03 2010 : Debug: with_alvarion_vsa_hack = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking preacct {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_acct_unique, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_acct_unique<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating acct_unique<br>Tue May 25 22:05:03 2010 : Debug: acct_unique {<br>Tue May 25 22:05:03 2010 : Debug: key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking accounting {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: (Loaded rlm_detail, checking if it's valid)<br>Tue May 25 22:05:03 2010 : Debug: Module: Linked to module rlm_detail<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating detail<br>Tue May 25 22:05:03 2010 : Debug: detail {<br>Tue May 25 22:05:03 2010 : Debug: detailfile = "/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"<br>Tue May 25 22:05:03 2010 : Debug: header = "%t"<br>Tue May 25 22:05:03 2010 : Debug: detailperm = 384<br>Tue May 25 22:05:03 2010 : Debug: dirperm = 493<br>Tue May 25 22:05:03 2010 : Debug: locking = no<br>Tue May 25 22:05:03 2010 : Debug: log_packet_header = no<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Module: Instantiating attr_filter.accounting_response<br>Tue May 25 22:05:03 2010 : Debug: ****** attr_filter_instantiate <br>Tue May 25 22:05:03 2010 : Debug: attr_filter attr_filter.accounting_response {<br>Tue May 25 22:05:03 2010 : Debug: attrsfile = "/usr/local/etc/raddb/attrs.accounting_response"<br>Tue May 25 22:05:03 2010 : Debug: key = "%{User-Name}"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: ****** getattrsfile <br>Tue May 25 22:05:03 2010 : Debug: Module: Checking session {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking post-proxy {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: Module: Checking post-auth {...} for more modules to load<br>Tue May 25 22:05:03 2010 : Debug: } # modules<br>Tue May 25 22:05:03 2010 : Debug: } # server<br>Tue May 25 22:05:03 2010 : Debug: radiusd: #### Opening IP addresses and Ports ####<br>Tue May 25 22:05:03 2010 : Debug: listen {<br>Tue May 25 22:05:03 2010 : Debug: type = "auth"<br>Tue May 25 22:05:03 2010 : Debug: ipaddr = *<br>Tue May 25 22:05:03 2010 : Debug: port = 0<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: listen {<br>Tue May 25 22:05:03 2010 : Debug: type = "acct"<br>Tue May 25 22:05:03 2010 : Debug: ipaddr = *<br>Tue May 25 22:05:03 2010 : Debug: port = 0<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: listen {<br>Tue May 25 22:05:03 2010 : Debug: type = "control"<br>Tue May 25 22:05:03 2010 : Debug: listen {<br>Tue May 25 22:05:03 2010 : Debug: socket = "/usr/local/var/run/radiusd/radiusd.sock"<br>Tue May 25 22:05:03 2010 : Debug: mode = "rw"<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: }<br>Tue May 25 22:05:03 2010 : Debug: Listening on authentication address * port 1812<br>Tue May 25 22:05:03 2010 : Debug: Listening on accounting address * port 1813<br>Tue May 25 22:05:03 2010 : Debug: Listening on command file /usr/local/var/run/radiusd/radiusd.sock<br>Tue May 25 22:05:03 2010 : Debug: Listening on proxy address * port 1814<br>Tue May 25 22:05:03 2010 : Info: Ready to process requests.<br>rad_recv: Access-Request packet from host 127.0.0.1 port 51554, id=236, length=67<br> User-Name = "test"<br> NAS-IP-Address = 209.87.252.247<br> Message-Authenticator = 0xc52fa993a307e116ff5680322dada9f4<br> NAS-Port = 0<br> EAP-Message = 0x02d200090174657374<br>Tue May 25 22:05:27 2010 : Info: +- entering group authorize {...}<br>Tue May 25 22:05:27 2010 : Info: ++[preprocess] returns ok<br>Tue May 25 22:05:27 2010 : Info: ++[chap] returns noop<br>Tue May 25 22:05:27 2010 : Info: ++[mschap] returns noop<br>Tue May 25 22:05:27 2010 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL<br>Tue May 25 22:05:27 2010 : Info: [suffix] No such realm "NULL"<br>Tue May 25 22:05:27 2010 : Info: ++[suffix] returns noop<br>Tue May 25 22:05:27 2010 : Info: [eap] EAP packet type response id 210 length 9<br>Tue May 25 22:05:27 2010 : Info: [eap] No EAP Start, assuming it's an on-going EAP conversation<br>Tue May 25 22:05:27 2010 : Info: ++[eap] returns updated<br>Tue May 25 22:05:27 2010 : Info: ++[unix] returns notfound<br>Tue May 25 22:05:27 2010 : Info: [files] users: Matched entry test at line 90<br>Tue May 25 22:05:27 2010 : Info: [files] expand: Hello, %{User-Name} -> Hello, test<br>Tue May 25 22:05:27 2010 : Info: ++[files] returns ok<br>Tue May 25 22:05:27 2010 : Info: ++[expiration] returns noop<br>Tue May 25 22:05:27 2010 : Info: ++[logintime] returns noop<br>Tue May 25 22:05:27 2010 : Info: [pap] Found existing Auth-Type, not changing it.<br>Tue May 25 22:05:27 2010 : Info: ++[pap] returns noop<br>Tue May 25 22:05:27 2010 : Info: Found Auth-Type = EAP<br>Tue May 25 22:05:27 2010 : Info: +- entering group authenticate {...}<br>Tue May 25 22:05:27 2010 : Info: [eap] EAP Identity<br>Tue May 25 22:05:27 2010 : Info: [eap] processing type md5<br>Tue May 25 22:05:27 2010 : Debug: rlm_eap_md5: Issuing Challenge<br>Tue May 25 22:05:27 2010 : Info: ++[eap] returns handled<br>Sending Access-Challenge of id 236 to 127.0.0.1 port 51554<br> Reply-Message = "Hello, test"<br> Framed-MTU = 1500<br> EAP-Message = 0x01d3001604108bb813bc0db605897f7ecea4036ca422<br> Message-Authenticator = 0x00000000000000000000000000000000<br> State = 0xbbd9622dbb0a661e27e315270e22da7a<br>Tue May 25 22:05:27 2010 : Info: Finished request 0.<br>Tue May 25 22:05:27 2010 : Debug: Going to the next request<br>Tue May 25 22:05:27 2010 : Debug: Waking up in 4.9 seconds.<br>rad_recv: Access-Request packet from host 127.0.0.1 port 51554, id=237, length=98<br> User-Name = "test"<br> NAS-IP-Address = 209.87.252.247<br> Message-Authenticator = 0xb553a994f93e5448f6a1b92aab6b2781<br> NAS-Port = 0<br> State = 0xbbd9622dbb0a661e27e315270e22da7a<br> EAP-Message = 0x02d30016041092ee897591f29bbfde36e49152e39501<br>Tue May 25 22:05:27 2010 : Info: +- entering group authorize {...}<br>Tue May 25 22:05:27 2010 : Info: ++[preprocess] returns ok<br>Tue May 25 22:05:27 2010 : Info: ++[chap] returns noop<br>Tue May 25 22:05:27 2010 : Info: ++[mschap] returns noop<br>Tue May 25 22:05:27 2010 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL<br>Tue May 25 22:05:27 2010 : Info: [suffix] No such realm "NULL"<br>Tue May 25 22:05:27 2010 : Info: ++[suffix] returns noop<br>Tue May 25 22:05:27 2010 : Info: [eap] EAP packet type response id 211 length 22<br>Tue May 25 22:05:27 2010 : Info: [eap] No EAP Start, assuming it's an on-going EAP conversation<br>Tue May 25 22:05:27 2010 : Info: ++[eap] returns updated<br>Tue May 25 22:05:27 2010 : Info: ++[unix] returns notfound<br>Tue May 25 22:05:27 2010 : Info: [files] users: Matched entry test at line 90<br>Tue May 25 22:05:27 2010 : Info: [files] expand: Hello, %{User-Name} -> Hello, test<br>Tue May 25 22:05:27 2010 : Info: ++[files] returns ok<br>Tue May 25 22:05:27 2010 : Info: ++[expiration] returns noop<br>Tue May 25 22:05:27 2010 : Info: ++[logintime] returns noop<br>Tue May 25 22:05:27 2010 : Info: [pap] Found existing Auth-Type, not changing it.<br>Tue May 25 22:05:27 2010 : Info: ++[pap] returns noop<br>Tue May 25 22:05:27 2010 : Info: Found Auth-Type = EAP<br>Tue May 25 22:05:27 2010 : Info: +- entering group authenticate {...}<br>Tue May 25 22:05:27 2010 : Info: [eap] Request found, released from the list<br>Tue May 25 22:05:27 2010 : Info: [eap] EAP/md5<br>Tue May 25 22:05:27 2010 : Info: [eap] processing type md5<br>Tue May 25 22:05:27 2010 : Info: [eap] Freeing handler<br>Tue May 25 22:05:27 2010 : Info: ++[eap] returns ok<br>Tue May 25 22:05:27 2010 : Info: +- entering group post-auth {...}<br>Tue May 25 22:05:27 2010 : Info: ++[exec] returns noop<br>Sending Access-Accept of id 237 to 127.0.0.1 port 51554<br> Reply-Message = "Hello, test"<br> Framed-MTU = 1500<br> EAP-Message = 0x03d30004<br> Message-Authenticator = 0x00000000000000000000000000000000<br> User-Name = "test"<br>Tue May 25 22:05:27 2010 : Info: Finished request 1.<br>Tue May 25 22:05:27 2010 : Debug: Going to the next request<br>Tue May 25 22:05:27 2010 : Debug: Waking up in 4.9 seconds.<br>Tue May 25 22:05:32 2010 : Info: Cleaning up request 0 ID 236 with timestamp +24<br>Tue May 25 22:05:32 2010 : Info: Cleaning up request 1 ID 237 with timestamp +24<br>Tue May 25 22:05:32 2010 : Info: Ready to process requests.<br><br><br><div></div><br><pre><br>在2010-05-26 12:53:11，"Alan DeKok" <aland@deployingradius.com> 写道：
>weiwei fang wrote:
>> As in RFC2865, this attribute "Framed-MTU = 1500" should not returned in
>> the access-challenge packet.
>>
>> however, I can still get it in the access-challenge packet. I don't know
>> why?
>
> So... what does debug mode say?
>
>> If I should add something in the
>> raddb/sites-available/attrs.access_challenge file?
>
> No.
>
> Alan DeKok.
>-
>List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
</pre><br><br><span title="neteasefooter"/><hr/>
<a href="http://ym.163.com/?from=od3" target="_blank">网易为中小企业免费提供企业邮箱（自主域名）</a>
</span>