Different behaviour of 2.2.0 and 2.2.6 in handling Post-auth-type reject
Wiesław Bieniek
wieslaw.bieniek at comarch.com
Fri May 29 12:06:24 CEST 2015
Hello,
I've tested 2.2.7 with my configuration and it seems it is still not
working.
When Reject comes from Post-Auth section the post atuh type rejecrt it
not called and attributes are not filtered out from Access reject Response.
Please see the attached debug output.
Regards
Wiesław
W dniu 2015-05-19 o 15:50, Alan DeKok pisze:
> Yes, it's resolved in 2.2.7.
>
> On May 19, 2015, at 9:46 AM, Wiesław Bieniek <wieslaw.bieniek at comarch.com> wrote:
>
>> Hello,
>>
>> Just for confirmation,
>>
>> Is this resolved/re-added in 2.2.7 ?
>> I haven't notice it in release notes, so I'm asking just to be sure.
>>
>> Regards
>> Wiesław
>>
>> W dniu 2014-12-03 o 20:10, Alan DeKok pisze:
>>> On Dec 3, 2014, at 8:13 AM, Wiesław Bieniek <wieslaw.bieniek at comarch.com> wrote:
>>>> And logs from 2.2.6
>>>>
>>>> Separated from 2.2.0 logs because server list does not accept messages larger than 100kB
>>> The original intention was to disallow rejecting in the post-auth stage. There is a use for that, so it should be re-added for 2.2.7.
>>>
>>> Alan DeKok.
>>>
>>> -
>>> List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
>>
>> --
>> *Wiesław Bieniek*
>> Projektant Telco BSS R&D
>>
>> tel. +48 12 646 12 66
>> website: www.comarch.pl <http://www.comarch.pl>
>> -
>> List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
>
> -
> List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
--
*Wiesław Bieniek*
Projektant Telco BSS R&D
tel. +48 12 646 12 66
website: www.comarch.pl <http://www.comarch.pl>
-------------- next part --------------
[wbieniek at galaxy sbin]$ ./radiusd -Xxxxxxxx
Fri May 29 11:56:12 2015 : Info: radiusd: FreeRADIUS Version 2.2.7, for host x86_64-unknown-linux-gnu, built on May 29 2015 at 10:48:40
Fri May 29 11:56:12 2015 : Debug: Server was built with:
Fri May 29 11:56:12 2015 : Debug: accounting
Fri May 29 11:56:12 2015 : Debug: authentication
Fri May 29 11:56:12 2015 : Debug: WITH_DHCP
Fri May 29 11:56:12 2015 : Debug: WITH_VMPS
Fri May 29 11:56:12 2015 : Debug: Server core libs:
Fri May 29 11:56:12 2015 : Debug: ssl: OpenSSL 0.9.8e-fips-rhel5 01 Jul 2008
Fri May 29 11:56:12 2015 : Info: Copyright (C) 1999-2015 The FreeRADIUS server project and contributors.
Fri May 29 11:56:12 2015 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
Fri May 29 11:56:12 2015 : Info: PARTICULAR PURPOSE.
Fri May 29 11:56:12 2015 : Info: You may redistribute copies of FreeRADIUS under the terms of the
Fri May 29 11:56:12 2015 : Info: GNU General Public License.
Fri May 29 11:56:12 2015 : Info: For more information about these matters, see the file named COPYRIGHT.
Fri May 29 11:56:12 2015 : Info: Starting - reading configuration files ...
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/radiusd.conf
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/../../../admin/log.conf
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/proxy.conf
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/clients.conf
Fri May 29 11:56:12 2015 : Debug: including files in directory ../etc/raddb/modules/
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/attr_filter
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/ldap
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/expiration
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/files
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/otp
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/smsotp
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/radutmp
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/krb5
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/pap
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/always
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/etc_group
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/checkval
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/AcctBufferWriter
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/cache
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/attr_rewrite
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/mschap
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/detail.example.com
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/counter
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/soh
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/radrelay
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/ntlm_auth
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/chap
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/rtdps2
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/expr
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/sql_log
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/digest
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/acct_unique
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/preprocess
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/mac2vlan
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/passwd
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/pam
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/sradutmp
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/opendirectory
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/mac2ip
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/replicate
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/dhcp_sqlippool
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/sql/mysql/ippool-dhcp.conf
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/realm
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/detail.log
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/perl
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/exec
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/echo
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/redis
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/wimax
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/inner-eap
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/logintime
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/cui
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/policy
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/detail
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/dynamic_clients
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/ippool
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/linelog
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/smbpasswd
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/unix
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/rediswho
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/modules/sqlcounter_expire_on_login
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/policy.conf
Fri May 29 11:56:12 2015 : Debug: including files in directory ../etc/raddb/sites-enabled/
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/sites-enabled/control-socket
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/sites-enabled/robust-proxy-accounting
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/proxyacc.conf
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/sites-enabled/default
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/sites-enabled/coa-server
Fri May 29 11:56:12 2015 : Debug: including configuration file ../etc/raddb/proxycoa.conf
Fri May 29 11:56:12 2015 : Debug: including dictionary file ../etc/raddb/dictionary
Fri May 29 11:56:12 2015 : Debug: main {
Fri May 29 11:56:12 2015 : Debug: name = "radiusd"
Fri May 29 11:56:12 2015 : Debug: prefix = "/home/wbieniek/DTAG_DT15.3/na/freeradius"
Fri May 29 11:56:12 2015 : Debug: localstatedir = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var"
Fri May 29 11:56:12 2015 : Debug: sbindir = "/home/wbieniek/DTAG_DT15.3/na/freeradius/sbin"
Fri May 29 11:56:12 2015 : Debug: logdir = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius"
Fri May 29 11:56:12 2015 : Debug: run_dir = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/run/radiusd"
Fri May 29 11:56:12 2015 : Debug: libdir = "/home/wbieniek/DTAG_DT15.3/na/freeradius/lib"
Fri May 29 11:56:12 2015 : Debug: radacctdir = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct"
Fri May 29 11:56:12 2015 : Debug: hostname_lookups = no
Fri May 29 11:56:12 2015 : Debug: max_request_time = 30
Fri May 29 11:56:12 2015 : Debug: cleanup_delay = 3
Fri May 29 11:56:12 2015 : Debug: max_requests = 16384
Fri May 29 11:56:12 2015 : Debug: pidfile = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/run/radiusd/radiusd.pid"
Fri May 29 11:56:12 2015 : Debug: checkrad = "/home/wbieniek/DTAG_DT15.3/na/freeradius/sbin/checkrad"
Fri May 29 11:56:12 2015 : Debug: debug_level = 0
Fri May 29 11:56:12 2015 : Debug: proxy_requests = yes
Fri May 29 11:56:12 2015 : Debug: log {
Fri May 29 11:56:12 2015 : Debug: stripped_names = no
Fri May 29 11:56:12 2015 : Debug: auth = no
Fri May 29 11:56:12 2015 : Debug: auth_badpass = no
Fri May 29 11:56:12 2015 : Debug: auth_goodpass = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: security {
Fri May 29 11:56:12 2015 : Debug: max_attributes = 200
Fri May 29 11:56:12 2015 : Debug: reject_delay = 0
Fri May 29 11:56:12 2015 : Debug: status_server = yes
Fri May 29 11:56:12 2015 : Debug: allow_vulnerable_openssl = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: radiusd: #### Loading Realms and Home Servers ####
Fri May 29 11:56:12 2015 : Debug: proxy server {
Fri May 29 11:56:12 2015 : Debug: retry_delay = 5
Fri May 29 11:56:12 2015 : Debug: retry_count = 3
Fri May 29 11:56:12 2015 : Debug: default_fallback = no
Fri May 29 11:56:12 2015 : Debug: dead_time = 120
Fri May 29 11:56:12 2015 : Debug: wake_all_if_all_dead = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server localhost {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 127.0.0.1
Fri May 29 11:56:12 2015 : Debug: port = 1812
Fri May 29 11:56:12 2015 : Debug: type = "auth"
Fri May 29 11:56:12 2015 : Debug: secret = "testing123"
Fri May 29 11:56:12 2015 : Debug: response_window = 20
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = yes
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "status-server"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 120
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: coa {
Fri May 29 11:56:12 2015 : Debug: irt = 2
Fri May 29 11:56:12 2015 : Debug: mrt = 16
Fri May 29 11:56:12 2015 : Debug: mrc = 5
Fri May 29 11:56:12 2015 : Debug: mrd = 30
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server ForwardingProxy_server {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 192.168.1.4
Fri May 29 11:56:12 2015 : Debug: port = 3801
Fri May 29 11:56:12 2015 : Debug: type = "auth+acct"
Fri May 29 11:56:12 2015 : Debug: secret = "testing123"
Fri May 29 11:56:12 2015 : Debug: response_window = 1
Fri May 29 11:56:12 2015 : Debug: no_response_fail = yes
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = no
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "request"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 120
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: username = "Are_YOU_ALIVE"
Fri May 29 11:56:12 2015 : Debug: password = "dummy"
Fri May 29 11:56:12 2015 : Debug: coa {
Fri May 29 11:56:12 2015 : Debug: irt = 2
Fri May 29 11:56:12 2015 : Debug: mrt = 16
Fri May 29 11:56:12 2015 : Debug: mrc = 5
Fri May 29 11:56:12 2015 : Debug: mrd = 30
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server ForwardingProxy_server2 {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 192.168.1.4
Fri May 29 11:56:12 2015 : Debug: port = 3803
Fri May 29 11:56:12 2015 : Debug: type = "auth+acct"
Fri May 29 11:56:12 2015 : Debug: secret = "testing123"
Fri May 29 11:56:12 2015 : Debug: response_window = 1
Fri May 29 11:56:12 2015 : Debug: no_response_fail = yes
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = no
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "request"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 120
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: username = "Are_YOU_ALIVE"
Fri May 29 11:56:12 2015 : Debug: password = "dummy"
Fri May 29 11:56:12 2015 : Debug: coa {
Fri May 29 11:56:12 2015 : Debug: irt = 2
Fri May 29 11:56:12 2015 : Debug: mrt = 16
Fri May 29 11:56:12 2015 : Debug: mrc = 5
Fri May 29 11:56:12 2015 : Debug: mrd = 30
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server ForwardingProxy_server3 {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 192.168.1.4
Fri May 29 11:56:12 2015 : Debug: port = 3805
Fri May 29 11:56:12 2015 : Debug: type = "auth+acct"
Fri May 29 11:56:12 2015 : Debug: secret = "testing123"
Fri May 29 11:56:12 2015 : Debug: response_window = 1
Fri May 29 11:56:12 2015 : Debug: no_response_fail = yes
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = no
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "request"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 120
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: username = "Are_YOU_ALIVE"
Fri May 29 11:56:12 2015 : Debug: password = "dummy"
Fri May 29 11:56:12 2015 : Debug: coa {
Fri May 29 11:56:12 2015 : Debug: irt = 2
Fri May 29 11:56:12 2015 : Debug: mrt = 16
Fri May 29 11:56:12 2015 : Debug: mrc = 5
Fri May 29 11:56:12 2015 : Debug: mrd = 30
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server ForwardingProxy_server4 {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 192.168.1.4
Fri May 29 11:56:12 2015 : Debug: port = 3807
Fri May 29 11:56:12 2015 : Debug: type = "auth+acct"
Fri May 29 11:56:12 2015 : Debug: secret = "testing123"
Fri May 29 11:56:12 2015 : Debug: response_window = 1
Fri May 29 11:56:12 2015 : Debug: no_response_fail = yes
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = no
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "request"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 120
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: username = "Are_YOU_ALIVE"
Fri May 29 11:56:12 2015 : Debug: password = "dummy"
Fri May 29 11:56:12 2015 : Debug: coa {
Fri May 29 11:56:12 2015 : Debug: irt = 2
Fri May 29 11:56:12 2015 : Debug: mrt = 16
Fri May 29 11:56:12 2015 : Debug: mrc = 5
Fri May 29 11:56:12 2015 : Debug: mrd = 30
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server AcctFileBuffer {
Fri May 29 11:56:12 2015 : Debug: virtual_server = "acct_file_storage"
Fri May 29 11:56:12 2015 : Debug: port = 0
Fri May 29 11:56:12 2015 : Debug: response_window = 30
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = yes
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "none"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 300
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server server_192.168.1.4_3800 {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 192.168.1.4
Fri May 29 11:56:12 2015 : Debug: port = 3800
Fri May 29 11:56:12 2015 : Debug: type = "coa"
Fri May 29 11:56:12 2015 : Debug: secret = "testing123"
Fri May 29 11:56:12 2015 : Debug: response_window = 30
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = yes
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "none"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 300
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server localCoa {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 127.0.0.1
Fri May 29 11:56:12 2015 : Debug: port = 4001
Fri May 29 11:56:12 2015 : Debug: type = "coa"
Fri May 29 11:56:12 2015 : Debug: secret = "CAserviceIF127001"
Fri May 29 11:56:12 2015 : Debug: src_ipaddr = "127.0.0.1"
Fri May 29 11:56:12 2015 : Debug: response_window = 30
Fri May 29 11:56:12 2015 : Debug: max_outstanding = 65536
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = yes
Fri May 29 11:56:12 2015 : Debug: zombie_period = 40
Fri May 29 11:56:12 2015 : Debug: status_check = "none"
Fri May 29 11:56:12 2015 : Debug: ping_interval = 30
Fri May 29 11:56:12 2015 : Debug: check_interval = 30
Fri May 29 11:56:12 2015 : Debug: num_answers_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: num_pings_to_alive = 3
Fri May 29 11:56:12 2015 : Debug: revive_interval = 300
Fri May 29 11:56:12 2015 : Debug: status_check_timeout = 4
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server_pool my_auth_failover {
Fri May 29 11:56:12 2015 : Debug: type = fail-over
Fri May 29 11:56:12 2015 : Debug: home_server = localhost
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: realm example.com {
Fri May 29 11:56:12 2015 : Debug: auth_pool = my_auth_failover
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: realm LOCAL {
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server_pool ForwardingProxy_pool {
Fri May 29 11:56:12 2015 : Debug: type = keyed-balance
Fri May 29 11:56:12 2015 : Debug: home_server = ForwardingProxy_server
Fri May 29 11:56:12 2015 : Debug: home_server = ForwardingProxy_server2
Fri May 29 11:56:12 2015 : Debug: home_server = ForwardingProxy_server3
Fri May 29 11:56:12 2015 : Debug: home_server = ForwardingProxy_server4
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: realm ForwardingProxy {
Fri May 29 11:56:12 2015 : Debug: pool = ForwardingProxy_pool
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: realm ~^[[:alnum:]].* {
Fri May 29 11:56:12 2015 : Debug: nostrip
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server_pool AcctProxyPool {
Fri May 29 11:56:12 2015 : Debug: type = load-balance
Fri May 29 11:56:12 2015 : Debug: virtual_server = AcctProxyServer
Fri May 29 11:56:12 2015 : Debug: home_server = ForwardingProxy_server
Fri May 29 11:56:12 2015 : Debug: fallback = AcctFileBuffer
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: realm AcctProxyRealm {
Fri May 29 11:56:12 2015 : Debug: acct_pool = AcctProxyPool
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server_pool Pool_192.168.1.4_3800 {
Fri May 29 11:56:12 2015 : Debug: type = load-balance
Fri May 29 11:56:12 2015 : Debug: home_server = server_192.168.1.4_3800
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: home_server_pool CoaProcessingPool {
Fri May 29 11:56:12 2015 : Debug: type = fail-over
Fri May 29 11:56:12 2015 : Debug: virtual_server = CoaProcessor
Fri May 29 11:56:12 2015 : Debug: home_server = localCoa
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: radiusd: #### Loading Clients ####
Fri May 29 11:56:12 2015 : Debug: client localhost {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 127.0.0.1
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = no
Fri May 29 11:56:12 2015 : Debug: secret = "CAserviceIF127001"
Fri May 29 11:56:12 2015 : Debug: nastype = "other"
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: client galaxy {
Fri May 29 11:56:12 2015 : Debug: ipaddr = 192.168.1.4
Fri May 29 11:56:12 2015 : Debug: require_message_authenticator = no
Fri May 29 11:56:12 2015 : Debug: secret = "testing123"
Fri May 29 11:56:12 2015 : Debug: nastype = "other"
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: radiusd: #### Instantiating modules ####
Fri May 29 11:56:12 2015 : Debug: instantiate {
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_exec, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_exec
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "exec" from file ../etc/raddb/modules/exec
Fri May 29 11:56:12 2015 : Debug: exec {
Fri May 29 11:56:12 2015 : Debug: wait = no
Fri May 29 11:56:12 2015 : Debug: input_pairs = "request"
Fri May 29 11:56:12 2015 : Debug: shell_escape = yes
Fri May 29 11:56:12 2015 : Debug: timeout = 10
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_expr, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_expr
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "expr" from file ../etc/raddb/modules/expr
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_expiration, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_expiration
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "expiration" from file ../etc/raddb/modules/expiration
Fri May 29 11:56:12 2015 : Debug: expiration {
Fri May 29 11:56:12 2015 : Debug: reply-message = "Password Has Expired "
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_logintime, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_logintime
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "logintime" from file ../etc/raddb/modules/logintime
Fri May 29 11:56:12 2015 : Debug: logintime {
Fri May 29 11:56:12 2015 : Debug: reply-message = "You are calling outside your allowed timespan "
Fri May 29 11:56:12 2015 : Debug: minimum-timeout = 60
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_rtdps2, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_rtdps2
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "rtdps2" from file ../etc/raddb/modules/rtdps2
Fri May 29 11:56:12 2015 : Info: SCM Plugin version: $Revision:: 28 $
Fri May 29 11:56:12 2015 : Debug: rtdps2 {
Fri May 29 11:56:12 2015 : Debug: remotenode = "wbradius at 192.168.1.4"
Fri May 29 11:56:12 2015 : Debug: cookie = "DTAGCookie"
Fri May 29 11:56:12 2015 : Debug: localnode = "fr_wbradius_"
Fri May 29 11:56:12 2015 : Debug: localhost = "192.168.1.4"
Fri May 29 11:56:12 2015 : Debug: destinationprocess = "RLMRTBSModule_RLMRTBS"
Fri May 29 11:56:12 2015 : Debug: processes = 1
Fri May 29 11:56:12 2015 : Debug: net_ticktime = 4
Fri May 29 11:56:12 2015 : Debug: report_threshold = 1000
Fri May 29 11:56:12 2015 : Debug: fake_processing = 0
Fri May 29 11:56:12 2015 : Debug: fake_processing_time = 4000
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: radiusd: #### Loading Virtual Servers ####
Fri May 29 11:56:12 2015 : Debug: server { # from file ../etc/raddb/radiusd.conf
Fri May 29 11:56:12 2015 : Debug: modules {
Fri May 29 11:56:12 2015 : Debug: Module: Creating Auth-Type = RTDPS
Fri May 29 11:56:12 2015 : Debug: Module: Checking authenticate {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_pap, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_pap
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "pap" from file ../etc/raddb/modules/pap
Fri May 29 11:56:12 2015 : Debug: pap {
Fri May 29 11:56:12 2015 : Debug: encryption_scheme = "auto"
Fri May 29 11:56:12 2015 : Debug: auto_header = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_chap, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_chap
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "chap" from file ../etc/raddb/modules/chap
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_mschap, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_mschap
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "mschap" from file ../etc/raddb/modules/mschap
Fri May 29 11:56:12 2015 : Debug: mschap {
Fri May 29 11:56:12 2015 : Debug: use_mppe = yes
Fri May 29 11:56:12 2015 : Debug: require_encryption = no
Fri May 29 11:56:12 2015 : Debug: require_strong = no
Fri May 29 11:56:12 2015 : Debug: with_ntdomain_hack = no
Fri May 29 11:56:12 2015 : Debug: allow_retry = yes
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: Module: Checking authorize {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_preprocess, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_preprocess
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "preprocess" from file ../etc/raddb/modules/preprocess
Fri May 29 11:56:12 2015 : Debug: preprocess {
Fri May 29 11:56:12 2015 : Debug: huntgroups = "../etc/raddb/huntgroups"
Fri May 29 11:56:12 2015 : Debug: hints = "../etc/raddb/hints"
Fri May 29 11:56:12 2015 : Debug: with_ascend_hack = no
Fri May 29 11:56:12 2015 : Debug: ascend_channels_per_line = 23
Fri May 29 11:56:12 2015 : Debug: with_ntdomain_hack = no
Fri May 29 11:56:12 2015 : Debug: with_specialix_jetstream_hack = no
Fri May 29 11:56:12 2015 : Debug: with_cisco_vsa_hack = no
Fri May 29 11:56:12 2015 : Debug: with_alvarion_vsa_hack = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/huntgroups
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/hints
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_realm, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_realm
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "IPASS" from file ../etc/raddb/modules/realm
Fri May 29 11:56:12 2015 : Debug: realm IPASS {
Fri May 29 11:56:12 2015 : Debug: format = "prefix"
Fri May 29 11:56:12 2015 : Debug: delimiter = "/"
Fri May 29 11:56:12 2015 : Debug: ignore_default = no
Fri May 29 11:56:12 2015 : Debug: ignore_null = yes
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "suffix" from file ../etc/raddb/modules/realm
Fri May 29 11:56:12 2015 : Debug: realm suffix {
Fri May 29 11:56:12 2015 : Debug: format = "suffix"
Fri May 29 11:56:12 2015 : Debug: delimiter = "@"
Fri May 29 11:56:12 2015 : Debug: ignore_default = no
Fri May 29 11:56:12 2015 : Debug: ignore_null = yes
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "realmpercent" from file ../etc/raddb/modules/realm
Fri May 29 11:56:12 2015 : Debug: realm realmpercent {
Fri May 29 11:56:12 2015 : Debug: format = "suffix"
Fri May 29 11:56:12 2015 : Debug: delimiter = "%"
Fri May 29 11:56:12 2015 : Debug: ignore_default = no
Fri May 29 11:56:12 2015 : Debug: ignore_null = yes
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "ntdomain" from file ../etc/raddb/modules/realm
Fri May 29 11:56:12 2015 : Debug: realm ntdomain {
Fri May 29 11:56:12 2015 : Debug: format = "prefix"
Fri May 29 11:56:12 2015 : Debug: delimiter = "\"
Fri May 29 11:56:12 2015 : Debug: ignore_default = no
Fri May 29 11:56:12 2015 : Debug: ignore_null = yes
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: Module: Checking preacct {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_acct_unique, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_acct_unique
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "acct_unique" from file ../etc/raddb/modules/acct_unique
Fri May 29 11:56:12 2015 : Debug: acct_unique {
Fri May 29 11:56:12 2015 : Debug: key = "User-Name, Acct-Session-Id, NAS-IP-Address, NAS-Identifier, NAS-Port"
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_files, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_files
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "files" from file ../etc/raddb/modules/files
Fri May 29 11:56:12 2015 : Debug: files {
Fri May 29 11:56:12 2015 : Debug: usersfile = "../etc/raddb/users"
Fri May 29 11:56:12 2015 : Debug: acctusersfile = "../etc/raddb/acct_users"
Fri May 29 11:56:12 2015 : Debug: preproxy_usersfile = "../etc/raddb/preproxy_users"
Fri May 29 11:56:12 2015 : Debug: compat = "no"
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/users
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/acct_users
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/preproxy_users
Fri May 29 11:56:12 2015 : Debug: Module: Checking accounting {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_attr_filter, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_attr_filter
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "attr_filter.accounting_response" from file ../etc/raddb/modules/attr_filter
Fri May 29 11:56:12 2015 : Debug: attr_filter attr_filter.accounting_response {
Fri May 29 11:56:12 2015 : Debug: attrsfile = "../etc/raddb/attrs.accounting_response"
Fri May 29 11:56:12 2015 : Debug: key = "%{User-Name}"
Fri May 29 11:56:12 2015 : Debug: relaxed = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/attrs.accounting_response
Fri May 29 11:56:12 2015 : Debug: Module: Checking session {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_radutmp, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_radutmp
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "radutmp" from file ../etc/raddb/modules/radutmp
Fri May 29 11:56:12 2015 : Debug: radutmp {
Fri May 29 11:56:12 2015 : Debug: filename = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radutmp"
Fri May 29 11:56:12 2015 : Debug: username = "%{User-Name}"
Fri May 29 11:56:12 2015 : Debug: case_sensitive = yes
Fri May 29 11:56:12 2015 : Debug: check_with_nas = yes
Fri May 29 11:56:12 2015 : Debug: perm = 384
Fri May 29 11:56:12 2015 : Debug: callerid = yes
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: Module: Checking pre-proxy {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "attr_filter.pre-proxy" from file ../etc/raddb/modules/attr_filter
Fri May 29 11:56:12 2015 : Debug: attr_filter attr_filter.pre-proxy {
Fri May 29 11:56:12 2015 : Debug: attrsfile = "../etc/raddb/attrs.pre-proxy"
Fri May 29 11:56:12 2015 : Debug: key = "%{Realm}"
Fri May 29 11:56:12 2015 : Debug: relaxed = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/attrs.pre-proxy
Fri May 29 11:56:12 2015 : Debug: Module: Checking post-proxy {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Checking post-auth {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "attr_filter.access_reject" from file ../etc/raddb/modules/attr_filter
Fri May 29 11:56:12 2015 : Debug: attr_filter attr_filter.access_reject {
Fri May 29 11:56:12 2015 : Debug: attrsfile = "../etc/raddb/attrs.access_reject"
Fri May 29 11:56:12 2015 : Debug: key = "%{User-Name}"
Fri May 29 11:56:12 2015 : Debug: relaxed = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/attrs.access_reject
Fri May 29 11:56:12 2015 : Debug: } # modules
Fri May 29 11:56:12 2015 : Debug: } # server
Fri May 29 11:56:12 2015 : Debug: server acct_file_storage { # from file ../etc/raddb/sites-enabled/robust-proxy-accounting
Fri May 29 11:56:12 2015 : Debug: modules {
Fri May 29 11:56:12 2015 : Debug: Module: Checking accounting {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_detail, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_detail
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "AcctBufferWriter" from file ../etc/raddb/modules/AcctBufferWriter
Fri May 29 11:56:12 2015 : Debug: detail AcctBufferWriter {
Fri May 29 11:56:12 2015 : Debug: detailfile = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-%Y%m%d:%H:%G"
Fri May 29 11:56:12 2015 : Debug: header = "%t"
Fri May 29 11:56:12 2015 : Debug: detailperm = 384
Fri May 29 11:56:12 2015 : Debug: dirperm = 493
Fri May 29 11:56:12 2015 : Debug: locking = no
Fri May 29 11:56:12 2015 : Debug: log_packet_header = no
Fri May 29 11:56:12 2015 : Debug: escape_filenames = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: } # modules
Fri May 29 11:56:12 2015 : Debug: } # server
Fri May 29 11:56:12 2015 : Debug: server AcctProxyServer { # from file ../etc/raddb/sites-enabled/robust-proxy-accounting
Fri May 29 11:56:12 2015 : Debug: modules {
Fri May 29 11:56:12 2015 : Debug: Module: Checking accounting {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Checking post-proxy {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: } # modules
Fri May 29 11:56:12 2015 : Debug: } # server
Fri May 29 11:56:12 2015 : Debug: server coa-server { # from file ../etc/raddb/sites-enabled/coa-server
Fri May 29 11:56:12 2015 : Debug: modules {
Fri May 29 11:56:12 2015 : Debug: Module: Checking pre-proxy {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: (Loaded rlm_always, checking if it's valid)
Fri May 29 11:56:12 2015 : Debug: Module: Linked to module rlm_always
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "ok" from file ../etc/raddb/modules/always
Fri May 29 11:56:12 2015 : Debug: always ok {
Fri May 29 11:56:12 2015 : Debug: rcode = "ok"
Fri May 29 11:56:12 2015 : Debug: simulcount = 0
Fri May 29 11:56:12 2015 : Debug: mpp = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "attr_filter.pre_coa" from file ../etc/raddb/modules/attr_filter
Fri May 29 11:56:12 2015 : Debug: attr_filter attr_filter.pre_coa {
Fri May 29 11:56:12 2015 : Debug: attrsfile = "../etc/raddb/attrs.pre_coa"
Fri May 29 11:56:12 2015 : Debug: key = "dummy"
Fri May 29 11:56:12 2015 : Debug: relaxed = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: reading pairlist file ../etc/raddb/attrs.pre_coa
Fri May 29 11:56:12 2015 : Debug: Module: Checking post-proxy {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Checking recv-coa {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Checking send-coa {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Instantiating module "fail" from file ../etc/raddb/modules/always
Fri May 29 11:56:12 2015 : Debug: always fail {
Fri May 29 11:56:12 2015 : Debug: rcode = "fail"
Fri May 29 11:56:12 2015 : Debug: simulcount = 0
Fri May 29 11:56:12 2015 : Debug: mpp = no
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: } # modules
Fri May 29 11:56:12 2015 : Debug: } # server
Fri May 29 11:56:12 2015 : Debug: server CoaProcessor { # from file ../etc/raddb/sites-enabled/coa-server
Fri May 29 11:56:12 2015 : Debug: modules {
Fri May 29 11:56:12 2015 : Debug: Module: Checking pre-proxy {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: Module: Checking post-proxy {...} for more modules to load
Fri May 29 11:56:12 2015 : Debug: } # modules
Fri May 29 11:56:12 2015 : Debug: } # server
Fri May 29 11:56:12 2015 : Debug: radiusd: #### Opening IP addresses and Ports ####
Fri May 29 11:56:12 2015 : Debug: listen {
Fri May 29 11:56:12 2015 : Debug: type = "control"
Fri May 29 11:56:12 2015 : Debug: listen {
Fri May 29 11:56:12 2015 : Debug: socket = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/run/radiusd/radiusd.sock"
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: listen {
Fri May 29 11:56:12 2015 : Debug: type = "auth"
Fri May 29 11:56:12 2015 : Debug: ipaddr = *
Fri May 29 11:56:12 2015 : Debug: port = 1816
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: listen {
Fri May 29 11:56:12 2015 : Debug: type = "acct"
Fri May 29 11:56:12 2015 : Debug: ipaddr = *
Fri May 29 11:56:12 2015 : Debug: port = 1817
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: listen {
Fri May 29 11:56:12 2015 : Debug: type = "coa"
Fri May 29 11:56:12 2015 : Debug: virtual_server = "coa-server"
Fri May 29 11:56:12 2015 : Debug: ipaddr = *
Fri May 29 11:56:12 2015 : Debug: port = 4001
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: listen {
Fri May 29 11:56:12 2015 : Debug: type = "detail"
Fri May 29 11:56:12 2015 : Debug: listen {
Fri May 29 11:56:12 2015 : Debug: filename = "/home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:*"
Fri May 29 11:56:12 2015 : Debug: load_factor = 10
Fri May 29 11:56:12 2015 : Debug: poll_interval = 1
Fri May 29 11:56:12 2015 : Debug: retry_interval = 30
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: }
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 49927
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 33952
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 34023
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 39809
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 50757
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 46091
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 41811
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 59879
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 46178
Fri May 29 11:56:12 2015 : Debug: ... adding new socket proxy address * port 42903
Fri May 29 11:56:12 2015 : Debug: Listening on command file /home/wbieniek/DTAG_DT15.3/na/freeradius/var/run/radiusd/radiusd.sock
Fri May 29 11:56:12 2015 : Debug: Listening on authentication address * port 1816
Fri May 29 11:56:12 2015 : Debug: Listening on accounting address * port 1817
Fri May 29 11:56:12 2015 : Debug: Listening on coa address * port 4001 as server coa-server
Fri May 29 11:56:12 2015 : Debug: Listening on detail file /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:* as server AcctProxyServer
Fri May 29 11:56:12 2015 : Debug: Detail listener /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:* state unopened signalled 0 waiting 1.000000 sec
Fri May 29 11:56:12 2015 : Debug: Listening on proxy address * port 1818
Fri May 29 11:56:12 2015 : Debug: Waking up in 0.9 seconds.
Fri May 29 11:56:13 2015 : Debug: Polling for detail file /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:*
Fri May 29 11:56:13 2015 : Debug: Detail listener /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:* state unopened signalled 0 waiting 0.868416 sec
Fri May 29 11:56:13 2015 : Debug: Waking up in 0.8 seconds.
Fri May 29 11:56:14 2015 : Debug: Polling for detail file /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:*
Fri May 29 11:56:14 2015 : Debug: Detail listener /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:* state unopened signalled 0 waiting 0.752340 sec
Fri May 29 11:56:14 2015 : Debug: Waking up in 0.7 seconds.
Fri May 29 11:56:15 2015 : Debug: Polling for detail file /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:*
Fri May 29 11:56:15 2015 : Debug: Detail listener /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:* state unopened signalled 0 waiting 0.824742 sec
Fri May 29 11:56:15 2015 : Debug: Waking up in 0.8 seconds.
Fri May 29 11:56:16 2015 : Debug: Polling for detail file /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:*
Fri May 29 11:56:16 2015 : Debug: Detail listener /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:* state unopened signalled 0 waiting 0.853071 sec
Fri May 29 11:56:16 2015 : Debug: Waking up in 0.8 seconds.
Fri May 29 11:56:17 2015 : Debug: Polling for detail file /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:*
Fri May 29 11:56:17 2015 : Debug: Detail listener /home/wbieniek/DTAG_DT15.3/na/freeradius/var/log/radius/radacct/AcctProxyBuffer/detail-*:* state unopened signalled 0 waiting 1.124684 sec
Fri May 29 11:56:17 2015 : Debug: Waking up in 1.1 seconds.
rad_recv: Access-Request packet from host 192.168.1.4 port 41728, id=243, length=195
Acct-Session-Id = "SESSION_SCMCUSTOMTEST028a-c1fe89811f00586c"
Event-Timestamp = "Oct 4 2011 11:04:25 CEST"
NAS-IP-Address = 10.132.24.6
Service-Type = Framed-User
Called-Station-Id = "bm09a-bnh1.de.m2mportal"
Calling-Station-Id = "4915159701108"
User-Name = "testing"
3GPP-IMSI = "262015947001264"
3GPP-User-Location-Info = 0x0162f21005a6000e
3GPP-IMEISV = "3536590115959340"
3GPP-SGSN-MCC-MNC = "26201"
3GPP-Charging-ID = 731148131
Fri May 29 11:56:17 2015 : Info: # Executing section authorize from file ../etc/raddb/sites-enabled/default
Fri May 29 11:56:17 2015 : Info: +group authorize {
Fri May 29 11:56:17 2015 : Info: ++[preprocess] = ok
Fri May 29 11:56:17 2015 : Info: [IPASS] No '/' in User-Name = "testing", skipping NULL due to config.
Fri May 29 11:56:17 2015 : Info: ++[IPASS] = noop
Fri May 29 11:56:17 2015 : Info: [suffix] No '@' in User-Name = "testing", skipping NULL due to config.
Fri May 29 11:56:17 2015 : Info: ++[suffix] = noop
Fri May 29 11:56:17 2015 : Info: [realmpercent] No '%' in User-Name = "testing", skipping NULL due to config.
Fri May 29 11:56:17 2015 : Info: ++[realmpercent] = noop
Fri May 29 11:56:17 2015 : Info: [ntdomain] No '\' in User-Name = "testing", skipping NULL due to config.
Fri May 29 11:56:17 2015 : Info: ++[ntdomain] = noop
Fri May 29 11:56:17 2015 : Debug: Begin CallBack
Fri May 29 11:56:17 2015 : Debug: -------------------------
Fri May 29 11:56:17 2015 : Debug: Creating new connection number: 1
Fri May 29 11:56:17 2015 : Debug: Starting as node fr_wbradius_1
Fri May 29 11:56:17 2015 : Debug: -------------------------
Fri May 29 11:56:17 2015 : Debug: Creating thread for listening to erlang messages
Fri May 29 11:56:17 2015 : Debug: Overload data: current requests 1
Fri May 29 11:56:17 2015 : Debug: Overload data: max requests 16384
Fri May 29 11:56:17 2015 : Debug: Overload data: queuing time 3.949 [ms]
Fri May 29 11:56:17 2015 : Debug: attributes count 12
Fri May 29 11:56:17 2015 : Debug: extra attributes count 12
Fri May 29 11:56:17 2015 : Debug: Attribute code: 44(44), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 55(55), vendor: 0, type: 3
Fri May 29 11:56:17 2015 : Debug: Attribute code: 4(4), vendor: 0, type: 2
Fri May 29 11:56:17 2015 : Debug: Attribute code: 6(6), vendor: 0, type: 1
Fri May 29 11:56:17 2015 : Debug: Attribute code: 30(30), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 31(31), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 1(1), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557441(1), vendor: 10415, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557462(22), vendor: 10415, type: 5
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557460(20), vendor: 10415, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557458(18), vendor: 10415, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557442(2), vendor: 10415, type: 1
Fri May 29 11:56:17 2015 : Debug: attributes count 0
Fri May 29 11:56:17 2015 : Debug: extra attributes count 0
Fri May 29 11:56:17 2015 : Debug: attributes count 0
Fri May 29 11:56:17 2015 : Debug: extra attributes count 1
Fri May 29 11:56:17 2015 : Debug: Attribute code: 1816(1816), vendor: 0, type: 1
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 6 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 0 . 6 type: 1
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 7 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 0 . 7 type: 1
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 682557458 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 10415 . 682557458 type: 0
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 1816 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 0 . 1816 type: 1
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 1000 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 0 . 1000 type: 1
Fri May 29 11:56:17 2015 : Info: ++[rtdps2] = ok
Fri May 29 11:56:17 2015 : Info: ++update control {
Fri May 29 11:56:17 2015 : Info: expand: %{Acct-Session-Id} -> SESSION_SCMCUSTOMTEST028a-c1fe89811f00586c
Fri May 29 11:56:17 2015 : Info: ++} # update control = noop
Fri May 29 11:56:17 2015 : Info: ++? if ("%{control:Proxy-To-realm}")
Fri May 29 11:56:17 2015 : Info: expand: %{control:Proxy-To-realm} ->
Fri May 29 11:56:17 2015 : Info: ? Evaluating ("%{control:Proxy-To-realm}") -> FALSE
Fri May 29 11:56:17 2015 : Info: ++? if ("%{control:Proxy-To-realm}") -> FALSE
Fri May 29 11:56:17 2015 : Info: ++? if ("%{control:subscriber}")
Fri May 29 11:56:17 2015 : Info: expand: %{control:subscriber} ->
Fri May 29 11:56:17 2015 : Info: ? Evaluating ("%{control:subscriber}") -> FALSE
Fri May 29 11:56:17 2015 : Info: ++? if ("%{control:subscriber}") -> FALSE
Fri May 29 11:56:17 2015 : Info: [pap] WARNING: Auth-Type already set. Not setting to PAP
Fri May 29 11:56:17 2015 : Info: ++[pap] = noop
Fri May 29 11:56:17 2015 : Info: +} # group authorize = ok
Fri May 29 11:56:17 2015 : Info: Found Auth-Type = Accept
Fri May 29 11:56:17 2015 : Info: Auth-Type = Accept, accepting the user
Fri May 29 11:56:17 2015 : Info: # Executing section post-auth from file ../etc/raddb/sites-enabled/default
Fri May 29 11:56:17 2015 : Info: +group post-auth {
Fri May 29 11:56:17 2015 : Info: ++update reply {
Fri May 29 11:56:17 2015 : Info: ++} # update reply = noop
Fri May 29 11:56:17 2015 : Debug: Begin CallBack
Fri May 29 11:56:17 2015 : Debug: Overload data: current requests 1
Fri May 29 11:56:17 2015 : Debug: Overload data: max requests 16384
Fri May 29 11:56:17 2015 : Debug: Overload data: queuing time 7.940 [ms]
Fri May 29 11:56:17 2015 : Debug: attributes count 12
Fri May 29 11:56:17 2015 : Debug: extra attributes count 12
Fri May 29 11:56:17 2015 : Debug: Attribute code: 44(44), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 55(55), vendor: 0, type: 3
Fri May 29 11:56:17 2015 : Debug: Attribute code: 4(4), vendor: 0, type: 2
Fri May 29 11:56:17 2015 : Debug: Attribute code: 6(6), vendor: 0, type: 1
Fri May 29 11:56:17 2015 : Debug: Attribute code: 30(30), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 31(31), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 1(1), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557441(1), vendor: 10415, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557462(22), vendor: 10415, type: 5
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557460(20), vendor: 10415, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557458(18), vendor: 10415, type: 0
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557442(2), vendor: 10415, type: 1
Fri May 29 11:56:17 2015 : Debug: attributes count 3
Fri May 29 11:56:17 2015 : Debug: extra attributes count 3
Fri May 29 11:56:17 2015 : Debug: Attribute code: 6(6), vendor: 0, type: 1
Fri May 29 11:56:17 2015 : Debug: Attribute code: 7(7), vendor: 0, type: 1
Fri May 29 11:56:17 2015 : Debug: Attribute code: 682557458(18), vendor: 10415, type: 0
Fri May 29 11:56:17 2015 : Debug: attributes count 3
Fri May 29 11:56:17 2015 : Debug: extra attributes count 3
Fri May 29 11:56:17 2015 : Debug: Attribute code: 1816(1816), vendor: 0, type: 1
Fri May 29 11:56:17 2015 : Debug: Attribute code: 1000(1000), vendor: 0, type: 1
Fri May 29 11:56:17 2015 : Debug: Attribute code: 1025(1025), vendor: 0, type: 0
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 589825 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 9 . 589825 type: 0
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 1816 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 0 . 1816 type: 1
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 1000 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 0 . 1000 type: 1
Fri May 29 11:56:17 2015 : Debug: Trying to get attr: 1025 from dictionary
Fri May 29 11:56:17 2015 : Debug: dict- attribute: 0 . 1025 type: 0
Fri May 29 11:56:17 2015 : Info: ++[rtdps2] = reject
Fri May 29 11:56:17 2015 : Info: +} # group post-auth = reject
Sending Access-Reject of id 243 to 192.168.1.4 port 41728
Service-Type = 0
Framed-Protocol = 0
3GPP-SGSN-MCC-MNC = "26201"
Cisco-AVPair := "h323-return-code=8"
Fri May 29 11:56:17 2015 : Info: Finished request 0.
Fri May 29 11:56:17 2015 : Debug: Going to the next request
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 4920 bytes
Desc: Kryptograficzna sygnatura S/MIME
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20150529/58d97480/attachment-0001.bin>
More information about the Freeradius-Users
mailing list