Re:Re: still about how to return some attributes only in Access-Accept packet

26 May 2010

      Sorry I use another account for this reply.

root@ubuntu:/usr/local/etc/raddb# radeapclient -x localhost auth testing123 </home/fang/freeradius-server-2.1.9/src/tests/eapmd5-01/req.txt

+++> About to send encoded packet:
        User-Name = "test"
        Cleartext-Password = "test"
        NAS-IP-Address = 209.87.252.247
        EAP-Code = Response
        EAP-Id = 210
        EAP-Type-Identity = "test"
        Message-Authenticator = 0x00
        NAS-Port = 0
<+++ EAP decoded packet:    (notice this is the accept-challenge packet and Framed-MTU = 1500 is there)
        Reply-Message = "Hello, test"
        Framed-MTU = 1500
        EAP-Message = 0x01d3001604108bb813bc0db605897f7ecea4036ca422
        Message-Authenticator = 0x62cb659c92b12b930fda931c6ba04af7
        State = 0xbbd9622dbb0a661e27e315270e22da7a
        EAP-Id = 211
        EAP-Code = Request
        EAP-Type-MD5 = 0x108bb813bc0db605897f7ecea4036ca422

+++> About to send encoded packet:
        User-Name = "test"
        Cleartext-Password = "test"
        NAS-IP-Address = 209.87.252.247
        EAP-Code = Response
        EAP-Id = 211
        Message-Authenticator = 0x00000000000000000000000000000000
        NAS-Port = 0
        EAP-Type-MD5 = 0x1092ee897591f29bbfde36e49152e39501
        State = 0xbbd9622dbb0a661e27e315270e22da7a
<+++ EAP decoded packet:
        Reply-Message = "Hello, test"
        Framed-MTU = 1500
        EAP-Message = 0x03d30004
        Message-Authenticator = 0xa7de7cd1e3a2025447c8a0bc17ec4c24
        User-Name = "test"
        EAP-Id = 211
        EAP-Code = Success

I use radiusd -xxX to open debug, and to verify the attr_filter module running process, I add the

printf("***** %s\n", __FUNC__); in every function of rlm_attr_filter.c

so you can notice something like:

****** attr_filter_instantiate ****** getattrsfile 

I think if the filter is trying to filter something, the function attr_filter_common should be executed, but not actually.

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. 

root@ubuntu:/home/fang/freeradius-server-2.1.9/src/modules/rlm_attr_filter# radiusd -xxX
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
Tue May 25 22:05:03 2010 : Info: Copyright (C) 1999-2009 The FreeRADIUS server project and contributors. 
Tue May 25 22:05:03 2010 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A 
Tue May 25 22:05:03 2010 : Info: PARTICULAR PURPOSE. 
Tue May 25 22:05:03 2010 : Info: You may redistribute copies of FreeRADIUS under the terms of the 
Tue May 25 22:05:03 2010 : Info: GNU General Public License v2. 
Tue May 25 22:05:03 2010 : Info: Starting - reading configuration files ...
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/radiusd.conf
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/proxy.conf
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/clients.conf
Tue May 25 22:05:03 2010 : Debug: including files in directory /usr/local/etc/raddb/modules/
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/acct_unique
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/detail.example.com
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/logintime
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/etc_group
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/realm
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/ntlm_auth
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/krb5
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/sqlcounter_expire_on_login
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/passwd
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/counter
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/detail.log
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/linelog
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/unix
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/smsotp
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/chap
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/policy
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/radutmp
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/always
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/exec
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/otp
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/detail
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/cui
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2vlan
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2ip
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/ldap
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/ippool
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_rewrite
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/expr
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/preprocess
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/digest
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/checkval
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/wimax
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/pap
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_filter
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/sradutmp
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/inner-eap
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/echo
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/mschap
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/sql_log
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/smbpasswd
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/expiration
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/pam
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/files
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/modules/perl
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/eap.conf
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/policy.conf
Tue May 25 22:05:03 2010 : Debug: including files in directory /usr/local/etc/raddb/sites-enabled/
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/control-socket
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel
Tue May 25 22:05:03 2010 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/default
Tue May 25 22:05:03 2010 : Debug: main {
Tue May 25 22:05:03 2010 : Debug:     allow_core_dumps = no
Tue May 25 22:05:03 2010 : Debug: }
Tue May 25 22:05:03 2010 : Debug: including dictionary file /usr/local/etc/raddb/dictionary
Tue May 25 22:05:03 2010 : Debug: main {
Tue May 25 22:05:03 2010 : Debug:     prefix = "/usr/local"
Tue May 25 22:05:03 2010 : Debug:     localstatedir = "/usr/local/var"
Tue May 25 22:05:03 2010 : Debug:     logdir = "/usr/local/var/log/radius"
Tue May 25 22:05:03 2010 : Debug:     libdir = "/usr/local/lib"
Tue May 25 22:05:03 2010 : Debug:     radacctdir = "/usr/local/var/log/radius/radacct"
Tue May 25 22:05:03 2010 : Debug:     hostname_lookups = no
Tue May 25 22:05:03 2010 : Debug:     max_request_time = 30
Tue May 25 22:05:03 2010 : Debug:     cleanup_delay = 5
Tue May 25 22:05:03 2010 : Debug:     max_requests = 1024
Tue May 25 22:05:03 2010 : Debug:     pidfile = "/usr/local/var/run/radiusd/radiusd.pid"
Tue May 25 22:05:03 2010 : Debug:     checkrad = "/usr/local/sbin/checkrad"
Tue May 25 22:05:03 2010 : Debug:     debug_level = 0
Tue May 25 22:05:03 2010 : Debug:     proxy_requests = yes
Tue May 25 22:05:03 2010 : Debug:  log {
Tue May 25 22:05:03 2010 : Debug:     stripped_names = no
Tue May 25 22:05:03 2010 : Debug:     auth = no
Tue May 25 22:05:03 2010 : Debug:     auth_badpass = no
Tue May 25 22:05:03 2010 : Debug:     auth_goodpass = no
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug:  security {
Tue May 25 22:05:03 2010 : Debug:     max_attributes = 200
Tue May 25 22:05:03 2010 : Debug:     reject_delay = 1
Tue May 25 22:05:03 2010 : Debug:     status_server = yes
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug: }
Tue May 25 22:05:03 2010 : Debug: radiusd: #### Loading Realms and Home Servers ####
Tue May 25 22:05:03 2010 : Debug:  proxy server {
Tue May 25 22:05:03 2010 : Debug:     retry_delay = 5
Tue May 25 22:05:03 2010 : Debug:     retry_count = 3
Tue May 25 22:05:03 2010 : Debug:     default_fallback = no
Tue May 25 22:05:03 2010 : Debug:     dead_time = 120
Tue May 25 22:05:03 2010 : Debug:     wake_all_if_all_dead = no
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug:  home_server localhost {
Tue May 25 22:05:03 2010 : Debug:     ipaddr = 127.0.0.1
Tue May 25 22:05:03 2010 : Debug:     port = 1812
Tue May 25 22:05:03 2010 : Debug:     type = "auth"
Tue May 25 22:05:03 2010 : Debug:     secret = "testing123"
Tue May 25 22:05:03 2010 : Debug:     response_window = 20
Tue May 25 22:05:03 2010 : Debug:     max_outstanding = 65536
Tue May 25 22:05:03 2010 : Debug:     require_message_authenticator = no
Tue May 25 22:05:03 2010 : Debug:     zombie_period = 40
Tue May 25 22:05:03 2010 : Debug:     status_check = "status-server"
Tue May 25 22:05:03 2010 : Debug:     ping_interval = 30
Tue May 25 22:05:03 2010 : Debug:     check_interval = 30
Tue May 25 22:05:03 2010 : Debug:     num_answers_to_alive = 3
Tue May 25 22:05:03 2010 : Debug:     num_pings_to_alive = 3
Tue May 25 22:05:03 2010 : Debug:     revive_interval = 120
Tue May 25 22:05:03 2010 : Debug:     status_check_timeout = 4
Tue May 25 22:05:03 2010 : Debug:     irt = 2
Tue May 25 22:05:03 2010 : Debug:     mrt = 16
Tue May 25 22:05:03 2010 : Debug:     mrc = 5
Tue May 25 22:05:03 2010 : Debug:     mrd = 30
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug:  home_server_pool my_auth_failover {
Tue May 25 22:05:03 2010 : Debug:     type = fail-over
Tue May 25 22:05:03 2010 : Debug:     home_server = localhost
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug:  realm example.com {
Tue May 25 22:05:03 2010 : Debug:     auth_pool = my_auth_failover
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug:  realm LOCAL {
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug: radiusd: #### Loading Clients ####
Tue May 25 22:05:03 2010 : Debug:  client localhost {
Tue May 25 22:05:03 2010 : Debug:     ipaddr = 127.0.0.1
Tue May 25 22:05:03 2010 : Debug:     require_message_authenticator = no
Tue May 25 22:05:03 2010 : Debug:     secret = "testing123"
Tue May 25 22:05:03 2010 : Debug:     nastype = "other"
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug: radiusd: #### Instantiating modules ####
Tue May 25 22:05:03 2010 : Debug:  instantiate {
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_exec, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_exec
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating exec
Tue May 25 22:05:03 2010 : Debug:   exec {
Tue May 25 22:05:03 2010 : Debug:     wait = no
Tue May 25 22:05:03 2010 : Debug:     input_pairs = "request"
Tue May 25 22:05:03 2010 : Debug:     shell_escape = yes
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_expr, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_expr
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating expr
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_expiration, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_expiration
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating expiration
Tue May 25 22:05:03 2010 : Debug:   expiration {
Tue May 25 22:05:03 2010 : Debug:     reply-message = "Password Has Expired  "
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_logintime, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_logintime
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating logintime
Tue May 25 22:05:03 2010 : Debug:   logintime {
Tue May 25 22:05:03 2010 : Debug:     reply-message = "You are calling outside your allowed timespan  "
Tue May 25 22:05:03 2010 : Debug:     minimum-timeout = 60
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug: radiusd: #### Loading Virtual Servers ####
Tue May 25 22:05:03 2010 : Debug: server inner-tunnel {
Tue May 25 22:05:03 2010 : Debug:  modules {
Tue May 25 22:05:03 2010 : Debug:  Module: Checking authenticate {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_pap, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_pap
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating pap
Tue May 25 22:05:03 2010 : Debug:   pap {
Tue May 25 22:05:03 2010 : Debug:     encryption_scheme = "auto"
Tue May 25 22:05:03 2010 : Debug:     auto_header = no
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_chap, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_chap
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating chap
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_mschap, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_mschap
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating mschap
Tue May 25 22:05:03 2010 : Debug:   mschap {
Tue May 25 22:05:03 2010 : Debug:     use_mppe = yes
Tue May 25 22:05:03 2010 : Debug:     require_encryption = no
Tue May 25 22:05:03 2010 : Debug:     require_strong = no
Tue May 25 22:05:03 2010 : Debug:     with_ntdomain_hack = no
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_unix, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_unix
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating unix
Tue May 25 22:05:03 2010 : Debug:   unix {
Tue May 25 22:05:03 2010 : Debug:     radwtmp = "/usr/local/var/log/radius/radwtmp"
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_eap, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_eap
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating eap
Tue May 25 22:05:03 2010 : Debug:   eap {
Tue May 25 22:05:03 2010 : Debug:     default_eap_type = "md5"
Tue May 25 22:05:03 2010 : Debug:     timer_expire = 60
Tue May 25 22:05:03 2010 : Debug:     ignore_unknown_eap_types = no
Tue May 25 22:05:03 2010 : Debug:     cisco_accounting_username_bug = no
Tue May 25 22:05:03 2010 : Debug:     max_sessions = 4096
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to sub-module rlm_eap_md5
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating eap-md5
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to sub-module rlm_eap_leap
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating eap-leap
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to sub-module rlm_eap_gtc
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating eap-gtc
Tue May 25 22:05:03 2010 : Debug:    gtc {
Tue May 25 22:05:03 2010 : Debug:     challenge = "Password: "
Tue May 25 22:05:03 2010 : Debug:     auth_type = "PAP"
Tue May 25 22:05:03 2010 : Debug:    }
Tue May 25 22:05:03 2010 : Debug: Ignoring EAP-Type/tls because we do not have OpenSSL support.
Tue May 25 22:05:03 2010 : Debug: Ignoring EAP-Type/ttls because we do not have OpenSSL support.
Tue May 25 22:05:03 2010 : Debug: Ignoring EAP-Type/peap because we do not have OpenSSL support.
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to sub-module rlm_eap_mschapv2
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating eap-mschapv2
Tue May 25 22:05:03 2010 : Debug:    mschapv2 {
Tue May 25 22:05:03 2010 : Debug:     with_ntdomain_hack = no
Tue May 25 22:05:03 2010 : Debug:    }
Tue May 25 22:05:03 2010 : Debug:  Module: Checking authorize {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_realm, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_realm
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating suffix
Tue May 25 22:05:03 2010 : Debug:   realm suffix {
Tue May 25 22:05:03 2010 : Debug:     format = "suffix"
Tue May 25 22:05:03 2010 : Debug:     delimiter = "@"
Tue May 25 22:05:03 2010 : Debug:     ignore_default = no
Tue May 25 22:05:03 2010 : Debug:     ignore_null = no
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_files, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_files
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating files
Tue May 25 22:05:03 2010 : Debug:   files {
Tue May 25 22:05:03 2010 : Debug:     usersfile = "/usr/local/etc/raddb/users"
Tue May 25 22:05:03 2010 : Debug:     acctusersfile = "/usr/local/etc/raddb/acct_users"
Tue May 25 22:05:03 2010 : Debug:     preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"
Tue May 25 22:05:03 2010 : Debug:     compat = "no"
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  Module: Checking session {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_radutmp, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_radutmp
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating radutmp
Tue May 25 22:05:03 2010 : Debug:   radutmp {
Tue May 25 22:05:03 2010 : Debug:     filename = "/usr/local/var/log/radius/radutmp"
Tue May 25 22:05:03 2010 : Debug:     username = "%{User-Name}"
Tue May 25 22:05:03 2010 : Debug:     case_sensitive = yes
Tue May 25 22:05:03 2010 : Debug:     check_with_nas = yes
Tue May 25 22:05:03 2010 : Debug:     perm = 384
Tue May 25 22:05:03 2010 : Debug:     callerid = yes
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  Module: Checking post-proxy {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:  Module: Checking post-auth {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_attr_filter, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_attr_filter
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating attr_filter.access_reject
Tue May 25 22:05:03 2010 : Debug: ****** attr_filter_instantiate 
Tue May 25 22:05:03 2010 : Debug:   attr_filter attr_filter.access_reject {
Tue May 25 22:05:03 2010 : Debug:     attrsfile = "/usr/local/etc/raddb/attrs.access_reject"
Tue May 25 22:05:03 2010 : Debug:     key = "%{User-Name}"
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug: ****** getattrsfile 
Tue May 25 22:05:03 2010 : Debug:  } # modules
Tue May 25 22:05:03 2010 : Debug: } # server
Tue May 25 22:05:03 2010 : Debug: server {
Tue May 25 22:05:03 2010 : Debug:  modules {
Tue May 25 22:05:03 2010 : Debug:  Module: Checking authenticate {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating attr_filter.access_challenge
Tue May 25 22:05:03 2010 : Debug: ****** attr_filter_instantiate 
Tue May 25 22:05:03 2010 : Debug:   attr_filter attr_filter.access_challenge {
Tue May 25 22:05:03 2010 : Debug:     attrsfile = "/usr/local/etc/raddb/attrs.access_challenge"
Tue May 25 22:05:03 2010 : Debug:     key = "%{User-Name}"
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug: ****** getattrsfile 
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_always, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_always
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating handled
Tue May 25 22:05:03 2010 : Debug:   always handled {
Tue May 25 22:05:03 2010 : Debug:     rcode = "handled"
Tue May 25 22:05:03 2010 : Debug:     simulcount = 0
Tue May 25 22:05:03 2010 : Debug:     mpp = no
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  Module: Checking authorize {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_preprocess, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_preprocess
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating preprocess
Tue May 25 22:05:03 2010 : Debug:   preprocess {
Tue May 25 22:05:03 2010 : Debug:     huntgroups = "/usr/local/etc/raddb/huntgroups"
Tue May 25 22:05:03 2010 : Debug:     hints = "/usr/local/etc/raddb/hints"
Tue May 25 22:05:03 2010 : Debug:     with_ascend_hack = no
Tue May 25 22:05:03 2010 : Debug:     ascend_channels_per_line = 23
Tue May 25 22:05:03 2010 : Debug:     with_ntdomain_hack = no
Tue May 25 22:05:03 2010 : Debug:     with_specialix_jetstream_hack = no
Tue May 25 22:05:03 2010 : Debug:     with_cisco_vsa_hack = no
Tue May 25 22:05:03 2010 : Debug:     with_alvarion_vsa_hack = no
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  Module: Checking preacct {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_acct_unique, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_acct_unique
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating acct_unique
Tue May 25 22:05:03 2010 : Debug:   acct_unique {
Tue May 25 22:05:03 2010 : Debug:     key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  Module: Checking accounting {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:     (Loaded rlm_detail, checking if it's valid)
Tue May 25 22:05:03 2010 : Debug:  Module: Linked to module rlm_detail
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating detail
Tue May 25 22:05:03 2010 : Debug:   detail {
Tue May 25 22:05:03 2010 : Debug:     detailfile = "/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
Tue May 25 22:05:03 2010 : Debug:     header = "%t"
Tue May 25 22:05:03 2010 : Debug:     detailperm = 384
Tue May 25 22:05:03 2010 : Debug:     dirperm = 493
Tue May 25 22:05:03 2010 : Debug:     locking = no
Tue May 25 22:05:03 2010 : Debug:     log_packet_header = no
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug:  Module: Instantiating attr_filter.accounting_response
Tue May 25 22:05:03 2010 : Debug: ****** attr_filter_instantiate 
Tue May 25 22:05:03 2010 : Debug:   attr_filter attr_filter.accounting_response {
Tue May 25 22:05:03 2010 : Debug:     attrsfile = "/usr/local/etc/raddb/attrs.accounting_response"
Tue May 25 22:05:03 2010 : Debug:     key = "%{User-Name}"
Tue May 25 22:05:03 2010 : Debug:   }
Tue May 25 22:05:03 2010 : Debug: ****** getattrsfile 
Tue May 25 22:05:03 2010 : Debug:  Module: Checking session {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:  Module: Checking post-proxy {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:  Module: Checking post-auth {...} for more modules to load
Tue May 25 22:05:03 2010 : Debug:  } # modules
Tue May 25 22:05:03 2010 : Debug: } # server
Tue May 25 22:05:03 2010 : Debug: radiusd: #### Opening IP addresses and Ports ####
Tue May 25 22:05:03 2010 : Debug: listen {
Tue May 25 22:05:03 2010 : Debug:     type = "auth"
Tue May 25 22:05:03 2010 : Debug:     ipaddr = *
Tue May 25 22:05:03 2010 : Debug:     port = 0
Tue May 25 22:05:03 2010 : Debug: }
Tue May 25 22:05:03 2010 : Debug: listen {
Tue May 25 22:05:03 2010 : Debug:     type = "acct"
Tue May 25 22:05:03 2010 : Debug:     ipaddr = *
Tue May 25 22:05:03 2010 : Debug:     port = 0
Tue May 25 22:05:03 2010 : Debug: }
Tue May 25 22:05:03 2010 : Debug: listen {
Tue May 25 22:05:03 2010 : Debug:     type = "control"
Tue May 25 22:05:03 2010 : Debug:  listen {
Tue May 25 22:05:03 2010 : Debug:     socket = "/usr/local/var/run/radiusd/radiusd.sock"
Tue May 25 22:05:03 2010 : Debug:     mode = "rw"
Tue May 25 22:05:03 2010 : Debug:  }
Tue May 25 22:05:03 2010 : Debug: }
Tue May 25 22:05:03 2010 : Debug: Listening on authentication address * port 1812
Tue May 25 22:05:03 2010 : Debug: Listening on accounting address * port 1813
Tue May 25 22:05:03 2010 : Debug: Listening on command file /usr/local/var/run/radiusd/radiusd.sock
Tue May 25 22:05:03 2010 : Debug: Listening on proxy address * port 1814
Tue May 25 22:05:03 2010 : Info: Ready to process requests.
rad_recv: Access-Request packet from host 127.0.0.1 port 51554, id=236, length=67
    User-Name = "test"
    NAS-IP-Address = 209.87.252.247
    Message-Authenticator = 0xc52fa993a307e116ff5680322dada9f4
    NAS-Port = 0
    EAP-Message = 0x02d200090174657374
Tue May 25 22:05:27 2010 : Info: +- entering group authorize {...}
Tue May 25 22:05:27 2010 : Info: ++[preprocess] returns ok
Tue May 25 22:05:27 2010 : Info: ++[chap] returns noop
Tue May 25 22:05:27 2010 : Info: ++[mschap] returns noop
Tue May 25 22:05:27 2010 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL
Tue May 25 22:05:27 2010 : Info: [suffix] No such realm "NULL"
Tue May 25 22:05:27 2010 : Info: ++[suffix] returns noop
Tue May 25 22:05:27 2010 : Info: [eap] EAP packet type response id 210 length 9
Tue May 25 22:05:27 2010 : Info: [eap] No EAP Start, assuming it's an on-going EAP conversation
Tue May 25 22:05:27 2010 : Info: ++[eap] returns updated
Tue May 25 22:05:27 2010 : Info: ++[unix] returns notfound
Tue May 25 22:05:27 2010 : Info: [files] users: Matched entry test at line 90
Tue May 25 22:05:27 2010 : Info: [files]     expand: Hello, %{User-Name} -> Hello, test
Tue May 25 22:05:27 2010 : Info: ++[files] returns ok
Tue May 25 22:05:27 2010 : Info: ++[expiration] returns noop
Tue May 25 22:05:27 2010 : Info: ++[logintime] returns noop
Tue May 25 22:05:27 2010 : Info: [pap] Found existing Auth-Type, not changing it.
Tue May 25 22:05:27 2010 : Info: ++[pap] returns noop
Tue May 25 22:05:27 2010 : Info: Found Auth-Type = EAP
Tue May 25 22:05:27 2010 : Info: +- entering group authenticate {...}
Tue May 25 22:05:27 2010 : Info: [eap] EAP Identity
Tue May 25 22:05:27 2010 : Info: [eap] processing type md5
Tue May 25 22:05:27 2010 : Debug: rlm_eap_md5: Issuing Challenge
Tue May 25 22:05:27 2010 : Info: ++[eap] returns handled
Sending Access-Challenge of id 236 to 127.0.0.1 port 51554
    Reply-Message = "Hello, test"
    Framed-MTU = 1500
    EAP-Message = 0x01d3001604108bb813bc0db605897f7ecea4036ca422
    Message-Authenticator = 0x00000000000000000000000000000000
    State = 0xbbd9622dbb0a661e27e315270e22da7a
Tue May 25 22:05:27 2010 : Info: Finished request 0.
Tue May 25 22:05:27 2010 : Debug: Going to the next request
Tue May 25 22:05:27 2010 : Debug: Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 127.0.0.1 port 51554, id=237, length=98
    User-Name = "test"
    NAS-IP-Address = 209.87.252.247
    Message-Authenticator = 0xb553a994f93e5448f6a1b92aab6b2781
    NAS-Port = 0
    State = 0xbbd9622dbb0a661e27e315270e22da7a
    EAP-Message = 0x02d30016041092ee897591f29bbfde36e49152e39501
Tue May 25 22:05:27 2010 : Info: +- entering group authorize {...}
Tue May 25 22:05:27 2010 : Info: ++[preprocess] returns ok
Tue May 25 22:05:27 2010 : Info: ++[chap] returns noop
Tue May 25 22:05:27 2010 : Info: ++[mschap] returns noop
Tue May 25 22:05:27 2010 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL
Tue May 25 22:05:27 2010 : Info: [suffix] No such realm "NULL"
Tue May 25 22:05:27 2010 : Info: ++[suffix] returns noop
Tue May 25 22:05:27 2010 : Info: [eap] EAP packet type response id 211 length 22
Tue May 25 22:05:27 2010 : Info: [eap] No EAP Start, assuming it's an on-going EAP conversation
Tue May 25 22:05:27 2010 : Info: ++[eap] returns updated
Tue May 25 22:05:27 2010 : Info: ++[unix] returns notfound
Tue May 25 22:05:27 2010 : Info: [files] users: Matched entry test at line 90
Tue May 25 22:05:27 2010 : Info: [files]     expand: Hello, %{User-Name} -> Hello, test
Tue May 25 22:05:27 2010 : Info: ++[files] returns ok
Tue May 25 22:05:27 2010 : Info: ++[expiration] returns noop
Tue May 25 22:05:27 2010 : Info: ++[logintime] returns noop
Tue May 25 22:05:27 2010 : Info: [pap] Found existing Auth-Type, not changing it.
Tue May 25 22:05:27 2010 : Info: ++[pap] returns noop
Tue May 25 22:05:27 2010 : Info: Found Auth-Type = EAP
Tue May 25 22:05:27 2010 : Info: +- entering group authenticate {...}
Tue May 25 22:05:27 2010 : Info: [eap] Request found, released from the list
Tue May 25 22:05:27 2010 : Info: [eap] EAP/md5
Tue May 25 22:05:27 2010 : Info: [eap] processing type md5
Tue May 25 22:05:27 2010 : Info: [eap] Freeing handler
Tue May 25 22:05:27 2010 : Info: ++[eap] returns ok
Tue May 25 22:05:27 2010 : Info: +- entering group post-auth {...}
Tue May 25 22:05:27 2010 : Info: ++[exec] returns noop
Sending Access-Accept of id 237 to 127.0.0.1 port 51554
    Reply-Message = "Hello, test"
    Framed-MTU = 1500
    EAP-Message = 0x03d30004
    Message-Authenticator = 0x00000000000000000000000000000000
    User-Name = "test"
Tue May 25 22:05:27 2010 : Info: Finished request 1.
Tue May 25 22:05:27 2010 : Debug: Going to the next request
Tue May 25 22:05:27 2010 : Debug: Waking up in 4.9 seconds.
Tue May 25 22:05:32 2010 : Info: Cleaning up request 0 ID 236 with timestamp +24
Tue May 25 22:05:32 2010 : Info: Cleaning up request 1 ID 237 with timestamp +24
Tue May 25 22:05:32 2010 : Info: Ready to process requests.

在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

Re:Re: still about how to return some attributes only in Access-Accept packet

方维维