February 2017 - Freeradius-Users - lists.freeradius.org

passwd and unlang in 3.0.4
by macservo 09 Feb '17

09 Feb '17

Hello, I´m trying to migrate an old installation of freeradius to a more recent system. Old version is 2.0.2 Target system is running CentOS 7 and the packaged version of freeradius, which is 3.0.4 The setup on the old server is using the passwd-module and unlang to bind the username (which comes from a certificate) to a mac-address and checks the validity of the username. MAC-address is added to request. /etc/raddb/mods-enabled/passwd: passwd maclist { filename = /etc/raddb/maclist format = "*User-Name:~mac-add" } /etc/raddb/sites-enabled/default: ... authorize { ... maclist if (notfound) { reject } if ( mac-add !~ /%{Calling-Station-ID}/i) { reject } ... /etc/raddb/dictionary: ATTRIBUTE mac-add 3005 string On access-request I´ll get the following error, and the check is not working: (1) [maclist] = ok (1) if (notfound) (1) if (notfound) -> FALSE (1) if ( mac-add !~ /%{Calling-Station-ID}/i) (1) EXPAND %{Calling-Station-ID} (1) --> 00236c94ed49 (1) ERROR: Failed retrieving values required to evaluate condition What am I doing wrong? Full debug and connection log below. Thank you very much. [root@radrad raddb]# radiusd -X radiusd: FreeRADIUS Version 3.0.4, for host x86_64-redhat-linux-gnu, built on Mar 5 2015 at 23:41:36 Copyright (C) 1999-2014 The FreeRADIUS server project and contributors There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE You may redistribute copies of FreeRADIUS under the terms of the GNU General Public License For more information about these matters, see the file named COPYRIGHT Starting - reading configuration files ... including dictionary file /usr/share/freeradius/dictionary including dictionary file /usr/share/freeradius/dictionary.dhcp including dictionary file /usr/share/freeradius/dictionary.vqp including dictionary file /etc/raddb/dictionary including configuration file /etc/raddb/radiusd.conf including configuration file /etc/raddb/proxy.conf including configuration file /etc/raddb/clients.conf including files in directory /etc/raddb/mods-enabled/ including configuration file /etc/raddb/mods-enabled/always including configuration file /etc/raddb/mods-enabled/attr_filter including configuration file /etc/raddb/mods-enabled/cache_eap including configuration file /etc/raddb/mods-enabled/chap including configuration file /etc/raddb/mods-enabled/detail including configuration file /etc/raddb/mods-enabled/detail.log including configuration file /etc/raddb/mods-enabled/dhcp including configuration file /etc/raddb/mods-enabled/digest including configuration file /etc/raddb/mods-enabled/dynamic_clients including configuration file /etc/raddb/mods-enabled/eap including configuration file /etc/raddb/mods-enabled/echo including configuration file /etc/raddb/mods-enabled/exec including configuration file /etc/raddb/mods-enabled/expiration including configuration file /etc/raddb/mods-enabled/expr including configuration file /etc/raddb/mods-enabled/files including configuration file /etc/raddb/mods-enabled/linelog including configuration file /etc/raddb/mods-enabled/logintime including configuration file /etc/raddb/mods-enabled/mschap including configuration file /etc/raddb/mods-enabled/ntlm_auth including configuration file /etc/raddb/mods-enabled/pap including configuration file /etc/raddb/mods-enabled/passwd including configuration file /etc/raddb/mods-enabled/preprocess including configuration file /etc/raddb/mods-enabled/radutmp including configuration file /etc/raddb/mods-enabled/realm including configuration file /etc/raddb/mods-enabled/replicate including configuration file /etc/raddb/mods-enabled/soh including configuration file /etc/raddb/mods-enabled/sradutmp including configuration file /etc/raddb/mods-enabled/unix including configuration file /etc/raddb/mods-enabled/unpack including configuration file /etc/raddb/mods-enabled/utf8 including files in directory /etc/raddb/policy.d/ including configuration file /etc/raddb/policy.d/accounting including configuration file /etc/raddb/policy.d/canonicalization including configuration file /etc/raddb/policy.d/control including configuration file /etc/raddb/policy.d/cui including configuration file /etc/raddb/policy.d/debug including configuration file /etc/raddb/policy.d/dhcp including configuration file /etc/raddb/policy.d/eap including configuration file /etc/raddb/policy.d/filter including configuration file /etc/raddb/policy.d/operator-name including files in directory /etc/raddb/sites-enabled/ including configuration file /etc/raddb/sites-enabled/default including configuration file /etc/raddb/sites-enabled/inner-tunnel main { security { user = "radiusd" group = "radiusd" allow_core_dumps = no } } main { name = "radiusd" prefix = "/usr" localstatedir = "/var" sbindir = "/usr/sbin" logdir = "/var/log/radius" run_dir = "/var/run/radiusd" libdir = "/usr/lib64/freeradius" radacctdir = "/var/log/radius/radacct" hostname_lookups = no max_request_time = 30 cleanup_delay = 5 max_requests = 1024 pidfile = "/var/www/path/radiusd.pid" checkrad = "/usr/sbin/checkrad" debug_level = 0 proxy_requests = yes log { stripped_names = no auth = yes auth_badpass = no auth_goodpass = no colourise = yes msg_denied = "You are already logged in - access denied" } security { max_attributes = 200 reject_delay = 1 status_server = yes } } radiusd: #### Loading Realms and Home Servers #### proxy server { retry_delay = 5 retry_count = 3 default_fallback = no dead_time = 120 wake_all_if_all_dead = no } home_server localhost { ipaddr = 127.0.0.1 port = 1812 type = "auth" secret = <<< secret >>> response_window = 20.000000 response_timeouts = 1 max_outstanding = 65536 zombie_period = 40 status_check = "status-server" ping_interval = 30 check_interval = 30 check_timeout = 4 num_answers_to_alive = 3 revive_interval = 120 coa { irt = 2 mrt = 16 mrc = 5 mrd = 30 } limit { max_connections = 16 max_requests = 0 lifetime = 0 idle_timeout = 0 } } home_server_pool my_auth_failover { type = fail-over home_server = localhost } realm example.com { auth_pool = my_auth_failover } realm LOCAL { } radiusd: #### Loading Clients #### client 192.168.2.42 { require_message_authenticator = no secret = <<< secret >>> shortname = "cisco-wlc" limit { max_connections = 16 lifetime = 0 idle_timeout = 30 } } No 'ipaddr' or 'ipv4addr' or 'ipv6addr' field found in client 192.168.2.42. Please fix your configuration Support for old-style clients will be removed in a future release client localhost { ipaddr = 127.0.0.1 require_message_authenticator = no secret = <<< secret >>> nas_type = "other" proto = "*" limit { max_connections = 16 lifetime = 0 idle_timeout = 30 } } client localhost_ipv6 { ipv6addr = ::1 require_message_authenticator = no secret = <<< secret >>> limit { max_connections = 16 lifetime = 0 idle_timeout = 30 } } radiusd: #### Instantiating modules #### instantiate { } modules { # Loaded module rlm_always # Instantiating module "reject" from file /etc/raddb/mods-enabled/always always reject { rcode = "reject" simulcount = 0 mpp = no } # Instantiating module "fail" from file /etc/raddb/mods-enabled/always always fail { rcode = "fail" simulcount = 0 mpp = no } # Instantiating module "ok" from file /etc/raddb/mods-enabled/always always ok { rcode = "ok" simulcount = 0 mpp = no } # Instantiating module "handled" from file /etc/raddb/mods-enabled/always always handled { rcode = "handled" simulcount = 0 mpp = no } # Instantiating module "invalid" from file /etc/raddb/mods-enabled/always always invalid { rcode = "invalid" simulcount = 0 mpp = no } # Instantiating module "userlock" from file /etc/raddb/mods-enabled/always always userlock { rcode = "userlock" simulcount = 0 mpp = no } # Instantiating module "notfound" from file /etc/raddb/mods-enabled/always always notfound { rcode = "notfound" simulcount = 0 mpp = no } # Instantiating module "noop" from file /etc/raddb/mods-enabled/always always noop { rcode = "noop" simulcount = 0 mpp = no } # Instantiating module "updated" from file /etc/raddb/mods-enabled/always always updated { rcode = "updated" simulcount = 0 mpp = no } # Loaded module rlm_attr_filter # Instantiating module "attr_filter.post-proxy" from file /etc/raddb/mods-enabled/attr_filter attr_filter attr_filter.post-proxy { filename = "/etc/raddb/mods-config/attr_filter/post-proxy" key = "%{Realm}" relaxed = no } reading pairlist file /etc/raddb/mods-config/attr_filter/post-proxy # Instantiating module "attr_filter.pre-proxy" from file /etc/raddb/mods-enabled/attr_filter attr_filter attr_filter.pre-proxy { filename = "/etc/raddb/mods-config/attr_filter/pre-proxy" key = "%{Realm}" relaxed = no } reading pairlist file /etc/raddb/mods-config/attr_filter/pre-proxy # Instantiating module "attr_filter.access_reject" from file /etc/raddb/mods-enabled/attr_filter attr_filter attr_filter.access_reject { filename = "/etc/raddb/mods-config/attr_filter/access_reject" key = "%{User-Name}" relaxed = no } reading pairlist file /etc/raddb/mods-config/attr_filter/access_reject # Instantiating module "attr_filter.access_challenge" from file /etc/raddb/mods-enabled/attr_filter attr_filter attr_filter.access_challenge { filename = "/etc/raddb/mods-config/attr_filter/access_challenge" key = "%{User-Name}" relaxed = no } reading pairlist file /etc/raddb/mods-config/attr_filter/access_challenge # Instantiating module "attr_filter.accounting_response" from file /etc/raddb/mods-enabled/attr_filter attr_filter attr_filter.accounting_response { filename = "/etc/raddb/mods-config/attr_filter/accounting_response" key = "%{User-Name}" relaxed = no } reading pairlist file /etc/raddb/mods-config/attr_filter/accounting_response # Loaded module rlm_cache # Instantiating module "cache_eap" from file /etc/raddb/mods-enabled/cache_eap cache cache_eap { key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}" ttl = 15 max_entries = 16384 epoch = 0 add_stats = no } # Loaded module rlm_chap # Instantiating module "chap" from file /etc/raddb/mods-enabled/chap # Loaded module rlm_detail # Instantiating module "detail" from file /etc/raddb/mods-enabled/detail detail { filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d" header = "%t" permissions = 384 locking = no log_packet_header = no } # Instantiating module "auth_log" from file /etc/raddb/mods-enabled/detail.log detail auth_log { filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d" header = "%t" permissions = 384 locking = no log_packet_header = no } rlm_detail (auth_log): 'User-Password' suppressed, will not appear in detail output # Instantiating module "reply_log" from file /etc/raddb/mods-enabled/detail.log detail reply_log { filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d" header = "%t" permissions = 384 locking = no log_packet_header = no } # Instantiating module "pre_proxy_log" from file /etc/raddb/mods-enabled/detail.log detail pre_proxy_log { filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d" header = "%t" permissions = 384 locking = no log_packet_header = no } # Instantiating module "post_proxy_log" from file /etc/raddb/mods-enabled/detail.log detail post_proxy_log { filename = "/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d" header = "%t" permissions = 384 locking = no log_packet_header = no } # Loaded module rlm_dhcp # Instantiating module "dhcp" from file /etc/raddb/mods-enabled/dhcp # Loaded module rlm_digest # Instantiating module "digest" from file /etc/raddb/mods-enabled/digest # Loaded module rlm_dynamic_clients # Instantiating module "dynamic_clients" from file /etc/raddb/mods-enabled/dynamic_clients # Loaded module rlm_eap # Instantiating module "eap" from file /etc/raddb/mods-enabled/eap eap { default_eap_type = "md5" timer_expire = 60 ignore_unknown_eap_types = no mod_accounting_username_bug = no max_sessions = 1024 } # Linked to sub-module rlm_eap_md5 # Linked to sub-module rlm_eap_leap # Linked to sub-module rlm_eap_gtc gtc { challenge = "Password: " auth_type = "PAP" } # Linked to sub-module rlm_eap_tls tls { tls = "tls-common" } tls-config tls-common { rsa_key_exchange = no dh_key_exchange = yes rsa_key_length = 512 dh_key_length = 512 verify_depth = 0 ca_path = "/etc/raddb/certs" pem_file_type = yes private_key_file = "/etc/raddb/certs/server.pem" certificate_file = "/etc/raddb/certs/server.pem" ca_file = "/etc/raddb/certs/ca.pem" private_key_password = <<< secret >>> dh_file = "/etc/raddb/certs/dh" random_file = "/etc/raddb/certs/random" fragment_size = 1024 include_length = yes check_crl = no cipher_list = "DEFAULT" ecdh_curve = "prime256v1" cache { enable = yes lifetime = 24 max_entries = 255 } verify { } ocsp { enable = no override_cert_url = yes url = "http://127.0.0.1/ocsp/" use_nonce = yes timeout = 0 softfail = yes } } # Linked to sub-module rlm_eap_ttls ttls { tls = "tls-common" default_eap_type = "md5" copy_request_to_tunnel = no use_tunneled_reply = no virtual_server = "inner-tunnel" include_length = yes require_client_cert = no } Using cached TLS configuration from previous invocation # Linked to sub-module rlm_eap_peap peap { tls = "tls-common" default_method = "mschapv2" copy_request_to_tunnel = no use_tunneled_reply = no proxy_tunneled_request_as_eap = yes virtual_server = "inner-tunnel" soh = no require_client_cert = no } Using cached TLS configuration from previous invocation # Linked to sub-module rlm_eap_mschapv2 mschapv2 { with_ntdomain_hack = no send_error = no } # Loaded module rlm_exec # Instantiating module "echo" from file /etc/raddb/mods-enabled/echo exec echo { wait = yes program = "/bin/echo %{User-Name}" input_pairs = "request" output_pairs = "reply" shell_escape = yes } # Instantiating module "exec" from file /etc/raddb/mods-enabled/exec exec { wait = no input_pairs = "request" shell_escape = yes timeout = 10 } # Loaded module rlm_expiration # Instantiating module "expiration" from file /etc/raddb/mods-enabled/expiration # Loaded module rlm_expr # Instantiating module "expr" from file /etc/raddb/mods-enabled/expr expr { safe_characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /" } # Loaded module rlm_files # Instantiating module "files" from file /etc/raddb/mods-enabled/files files { filename = "/etc/raddb/mods-config/files/authorize" usersfile = "/etc/raddb/mods-config/files/authorize" acctusersfile = "/etc/raddb/mods-config/files/accounting" preproxy_usersfile = "/etc/raddb/mods-config/files/pre-proxy" compat = "cistron" } reading pairlist file /etc/raddb/mods-config/files/authorize [/etc/raddb/mods-config/files/authorize]:181 Cistron compatibility checks for entry DEFAULT ... [/etc/raddb/mods-config/files/authorize]:188 Cistron compatibility checks for entry DEFAULT ... [/etc/raddb/mods-config/files/authorize]:195 Cistron compatibility checks for entry DEFAULT ... reading pairlist file /etc/raddb/mods-config/files/authorize [/etc/raddb/mods-config/files/authorize]:181 Cistron compatibility checks for entry DEFAULT ... [/etc/raddb/mods-config/files/authorize]:188 Cistron compatibility checks for entry DEFAULT ... [/etc/raddb/mods-config/files/authorize]:195 Cistron compatibility checks for entry DEFAULT ... reading pairlist file /etc/raddb/mods-config/files/accounting reading pairlist file /etc/raddb/mods-config/files/pre-proxy # Loaded module rlm_linelog # Instantiating module "linelog" from file /etc/raddb/mods-enabled/linelog linelog { filename = "/var/log/radius/linelog" permissions = 384 format = "This is a log message for %{User-Name}" reference = "messages.%{%{Packet-Type}:-default}" } # Instantiating module "log_accounting" from file /etc/raddb/mods-enabled/linelog linelog log_accounting { filename = "/var/log/radius/linelog-accounting" permissions = 384 format = "" reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}" } # Loaded module rlm_logintime # Instantiating module "logintime" from file /etc/raddb/mods-enabled/logintime logintime { minimum_timeout = 60 } # Loaded module rlm_mschap # Instantiating module "mschap" from file /etc/raddb/mods-enabled/mschap mschap { use_mppe = yes require_encryption = no require_strong = no with_ntdomain_hack = yes passchange { } allow_retry = yes } # Instantiating module "ntlm_auth" from file /etc/raddb/mods-enabled/ntlm_auth exec ntlm_auth { wait = yes program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN --username=%{mschap:User-Name} --password=%{User-Password}" shell_escape = yes } # Loaded module rlm_pap # Instantiating module "pap" from file /etc/raddb/mods-enabled/pap pap { normalise = yes } # Loaded module rlm_passwd # Instantiating module "maclist" from file /etc/raddb/mods-enabled/passwd passwd maclist { filename = "/etc/raddb/maclist" format = "*User-Name:~mac-add" delimiter = ":" ignore_nislike = yes ignore_empty = yes allow_multiple_keys = no hash_size = 100 } rlm_passwd: nfields: 2 keyfield 0(User-Name) listable: no # Loaded module rlm_preprocess # Instantiating module "preprocess" from file /etc/raddb/mods-enabled/preprocess preprocess { huntgroups = "/etc/raddb/mods-config/preprocess/huntgroups" hints = "/etc/raddb/mods-config/preprocess/hints" with_ascend_hack = no ascend_channels_per_line = 23 with_ntdomain_hack = no with_specialix_jetstream_hack = no with_cisco_vsa_hack = no with_alvarion_vsa_hack = no } reading pairlist file /etc/raddb/mods-config/preprocess/huntgroups reading pairlist file /etc/raddb/mods-config/preprocess/hints # Loaded module rlm_radutmp # Instantiating module "radutmp" from file /etc/raddb/mods-enabled/radutmp radutmp { filename = "/var/log/radius/radutmp" username = "%{User-Name}" case_sensitive = yes check_with_nas = yes permissions = 384 caller_id = yes } # Loaded module rlm_realm # Instantiating module "IPASS" from file /etc/raddb/mods-enabled/realm realm IPASS { format = "prefix" delimiter = "/" ignore_default = no ignore_null = no } # Instantiating module "suffix" from file /etc/raddb/mods-enabled/realm realm suffix { format = "suffix" delimiter = "@" ignore_default = no ignore_null = no } # Instantiating module "realmpercent" from file /etc/raddb/mods-enabled/realm realm realmpercent { format = "suffix" delimiter = "%" ignore_default = no ignore_null = no } # Instantiating module "ntdomain" from file /etc/raddb/mods-enabled/realm realm ntdomain { format = "prefix" delimiter = "\" ignore_default = no ignore_null = no } # Loaded module rlm_replicate # Instantiating module "replicate" from file /etc/raddb/mods-enabled/replicate # Loaded module rlm_soh # Instantiating module "soh" from file /etc/raddb/mods-enabled/soh soh { dhcp = yes } # Instantiating module "sradutmp" from file /etc/raddb/mods-enabled/sradutmp radutmp sradutmp { filename = "/var/log/radius/sradutmp" username = "%{User-Name}" case_sensitive = yes check_with_nas = yes permissions = 420 caller_id = no } # Loaded module rlm_unix # Instantiating module "unix" from file /etc/raddb/mods-enabled/unix unix { radwtmp = "/var/log/radius/radwtmp" } # Loaded module rlm_unpack # Instantiating module "unpack" from file /etc/raddb/mods-enabled/unpack # Loaded module rlm_utf8 # Instantiating module "utf8" from file /etc/raddb/mods-enabled/utf8 } # modules radiusd: #### Loading Virtual Servers #### server { # from file /etc/raddb/radiusd.conf } # server server default { # from file /etc/raddb/sites-enabled/default # Creating Auth-Type = digest # Loading authenticate {...} # Loading authorize {...} Ignoring "sql" (see raddb/mods-available/README.rst) Ignoring "ldap" (see raddb/mods-available/README.rst) # Loading preacct {...} # Loading accounting {...} # Loading post-proxy {...} # Loading post-auth {...} } # server default server inner-tunnel { # from file /etc/raddb/sites-enabled/inner-tunnel # Loading authenticate {...} # Loading authorize {...} # Loading session {...} # Loading post-proxy {...} # Loading post-auth {...} } # server inner-tunnel radiusd: #### Opening IP addresses and Ports #### listen { type = "auth" ipaddr = * port = 0 limit { max_connections = 16 lifetime = 0 idle_timeout = 30 } } listen { type = "acct" ipaddr = * port = 0 limit { max_connections = 16 lifetime = 0 idle_timeout = 30 } } listen { type = "auth" ipv6addr = :: port = 0 limit { max_connections = 16 lifetime = 0 idle_timeout = 30 } } listen { type = "acct" ipv6addr = :: port = 0 limit { max_connections = 16 lifetime = 0 idle_timeout = 30 } } listen { type = "auth" ipaddr = 127.0.0.1 port = 18120 } Listening on auth address * port 1812 as server default Listening on acct address * port 1813 as server default Listening on auth address :: port 1812 as server default Listening on acct address :: port 1813 as server default Listening on auth address 127.0.0.1 port 18120 as server inner-tunnel Opening new proxy socket 'proxy address * port 0' Listening on proxy address * port 44568 Ready to process requests Received Access-Request Id 16 from 192.168.2.42:32769 to 10.1.2.27:1812 length 241 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x0201000d014d61633238342d31 Message-Authenticator = 0x1ab41330ba943539bcc92c8db9966a0f (0) Received Access-Request packet from host 192.168.2.42 port 32769, id=16, length=241 (0) User-Name = 'Mac284-1' (0) Chargeable-User-Identity = 0x00 (0) Location-Capable = Civix-Location (0) Calling-Station-Id = '00236c94ed49' (0) Called-Station-Id = '34a84edc9b40:test' (0) NAS-Port = 1 (0) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (0) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (0) NAS-IP-Address = 192.168.2.42 (0) NAS-Identifier = 'cisco-wlc' (0) Airespace-Wlan-Id = 6 (0) Service-Type = Framed-User (0) Framed-MTU = 1300 (0) NAS-Port-Type = Wireless-802.11 (0) EAP-Message = 0x0201000d014d61633238342d31 (0) Message-Authenticator = 0x1ab41330ba943539bcc92c8db9966a0f (0) # Executing section authorize from file /etc/raddb/sites-enabled/default (0) authorize { (0) filter_username filter_username { (0) if (!&User-Name) (0) if (!&User-Name) -> FALSE (0) if (&User-Name =~ / /) (0) if (&User-Name =~ / /) -> FALSE (0) if (&User-Name =~ /@.*@/ ) (0) if (&User-Name =~ /@.*@/ ) -> FALSE (0) if (&User-Name =~ /\\.\\./ ) (0) if (&User-Name =~ /\\.\\./ ) -> FALSE (0) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (0) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (0) if (&User-Name =~ /\\.$/) (0) if (&User-Name =~ /\\.$/) -> FALSE (0) if (&User-Name =~ /(a)\\./) (0) if (&User-Name =~ /(a)\\./) -> FALSE (0) } # filter_username filter_username = notfound (0) [preprocess] = ok (0) [chap] = noop (0) [mschap] = noop (0) [digest] = noop (0) suffix : Checking for suffix after "@" (0) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (0) suffix : No such realm "NULL" (0) [suffix] = noop (0) if (Called-Station-ID =~ /:test$/) (0) if (Called-Station-ID =~ /:test$/) -> TRUE (0) if (Called-Station-ID =~ /:test$/) { (0) eap : Peer sent code Response (2) ID 1 length 13 (0) eap : EAP-Identity reply, returning 'ok' so we can short-circuit the rest of authorize (0) [eap] = ok (0) } # if (Called-Station-ID =~ /:test$/) = ok (0) [files] = noop (0) [expiration] = noop (0) [logintime] = noop (0) WARNING: pap : No "known good" password found for the user. Not setting Auth-Type (0) WARNING: pap : Authentication will fail unless a "known good" password is available (0) [pap] = noop (0) } # authorize = ok (0) Found Auth-Type = EAP (0) # Executing group from file /etc/raddb/sites-enabled/default (0) authenticate { (0) eap : Peer sent method Identity (1) (0) eap : Calling eap_md5 to process EAP data (0) eap_md5 : Issuing MD5 Challenge (0) eap : New EAP session, adding 'State' attribute to reply 0x3286ec463284e8a3 (0) [eap] = handled (0) } # authenticate = handled (0) Sending Access-Challenge packet to host 192.168.2.42 port 32769, id=16, length=0 (0) EAP-Message = 0x01020016041022c02db351bae08c46ab81cb684e2bc8 (0) Message-Authenticator = 0x00000000000000000000000000000000 (0) State = 0x3286ec463284e8a3af3e7e5c1128d5dc Sending Access-Challenge Id 16 from 10.1.2.27:1812 to 192.168.2.42:32769 EAP-Message = 0x01020016041022c02db351bae08c46ab81cb684e2bc8 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3286ec463284e8a3af3e7e5c1128d5dc (0) Finished request Waking up in 0.3 seconds. Received Access-Request Id 17 from 192.168.2.42:32769 to 10.1.2.27:1812 length 252 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x02020006030d State = 0x3286ec463284e8a3af3e7e5c1128d5dc Message-Authenticator = 0x3951d3d7ec148c5021d91af9b33ae8f4 (1) Received Access-Request packet from host 192.168.2.42 port 32769, id=17, length=252 (1) User-Name = 'Mac284-1' (1) Chargeable-User-Identity = 0x00 (1) Location-Capable = Civix-Location (1) Calling-Station-Id = '00236c94ed49' (1) Called-Station-Id = '34a84edc9b40:test' (1) NAS-Port = 1 (1) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (1) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (1) NAS-IP-Address = 192.168.2.42 (1) NAS-Identifier = 'cisco-wlc' (1) Airespace-Wlan-Id = 6 (1) Service-Type = Framed-User (1) Framed-MTU = 1300 (1) NAS-Port-Type = Wireless-802.11 (1) EAP-Message = 0x02020006030d (1) State = 0x3286ec463284e8a3af3e7e5c1128d5dc (1) Message-Authenticator = 0x3951d3d7ec148c5021d91af9b33ae8f4 (1) # Executing section authorize from file /etc/raddb/sites-enabled/default (1) authorize { (1) filter_username filter_username { (1) if (!&User-Name) (1) if (!&User-Name) -> FALSE (1) if (&User-Name =~ / /) (1) if (&User-Name =~ / /) -> FALSE (1) if (&User-Name =~ /@.*@/ ) (1) if (&User-Name =~ /@.*@/ ) -> FALSE (1) if (&User-Name =~ /\\.\\./ ) (1) if (&User-Name =~ /\\.\\./ ) -> FALSE (1) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (1) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (1) if (&User-Name =~ /\\.$/) (1) if (&User-Name =~ /\\.$/) -> FALSE (1) if (&User-Name =~ /(a)\\./) (1) if (&User-Name =~ /(a)\\./) -> FALSE (1) } # filter_username filter_username = notfound (1) [preprocess] = ok (1) [chap] = noop (1) [mschap] = noop (1) [digest] = noop (1) suffix : Checking for suffix after "@" (1) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (1) suffix : No such realm "NULL" (1) [suffix] = noop (1) if (Called-Station-ID =~ /:test$/) (1) if (Called-Station-ID =~ /:test$/) -> TRUE (1) if (Called-Station-ID =~ /:test$/) { (1) eap : Peer sent code Response (2) ID 2 length 6 (1) eap : No EAP Start, assuming it's an on-going EAP conversation (1) [eap] = updated (1) [files] = noop (1) [maclist] = ok (1) if (notfound) (1) if (notfound) -> FALSE (1) if ( mac-add !~ /%{Calling-Station-ID}/i) (1) EXPAND %{Calling-Station-ID} (1) --> 00236c94ed49 (1) ERROR: Failed retrieving values required to evaluate condition (1) } # if (Called-Station-ID =~ /:test$/) = updated (1) [files] = noop (1) [expiration] = noop (1) [logintime] = noop (1) WARNING: pap : No "known good" password found for the user. Not setting Auth-Type (1) WARNING: pap : Authentication will fail unless a "known good" password is available (1) [pap] = noop (1) } # authorize = updated (1) Found Auth-Type = EAP (1) # Executing group from file /etc/raddb/sites-enabled/default (1) authenticate { (1) eap : Expiring EAP session with state 0x3286ec463284e8a3 (1) eap : Finished EAP session with state 0x3286ec463284e8a3 (1) eap : Previous EAP request found for state 0x3286ec463284e8a3, released from the list (1) eap : Peer sent method NAK (3) (1) eap : Found mutually acceptable type TLS (13) (1) eap : Calling eap_tls to process EAP data (1) eap_tls : Flushing SSL sessions (of #0) (1) eap_tls : Requiring client certificate (1) eap_tls : Initiate (1) eap_tls : Requiring client certificate (1) eap_tls : Start returned 1 (1) eap : New EAP session, adding 'State' attribute to reply 0x3286ec463385e1a3 (1) [eap] = handled (1) } # authenticate = handled (1) Sending Access-Challenge packet to host 192.168.2.42 port 32769, id=17, length=0 (1) EAP-Message = 0x010300060d20 (1) Message-Authenticator = 0x00000000000000000000000000000000 (1) State = 0x3286ec463385e1a3af3e7e5c1128d5dc Sending Access-Challenge Id 17 from 10.1.2.27:1812 to 192.168.2.42:32769 EAP-Message = 0x010300060d20 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3286ec463385e1a3af3e7e5c1128d5dc (1) Finished request Waking up in 0.3 seconds. Received Access-Request Id 18 from 192.168.2.42:32769 to 10.1.2.27:1812 length 398 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x020300980d800000008e1603010089010000850301589c70aac65ee802893e9669a76d4b6ba5245eadb48ca2b02ca1a2c67e308b5e00004a00ffc024c023c00ac009c007c008c028c027c014c013c011c012c026c025c02ac029c005c004c002c003c00fc00ec00cc00d003d003c002f000500040035000a0067006b00330039001601000012000a00080006001700180019000b00020100 State = 0x3286ec463385e1a3af3e7e5c1128d5dc Message-Authenticator = 0x544710c4d800cca04ae8d70b22ba2bd6 (2) Received Access-Request packet from host 192.168.2.42 port 32769, id=18, length=398 (2) User-Name = 'Mac284-1' (2) Chargeable-User-Identity = 0x00 (2) Location-Capable = Civix-Location (2) Calling-Station-Id = '00236c94ed49' (2) Called-Station-Id = '34a84edc9b40:test' (2) NAS-Port = 1 (2) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (2) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (2) NAS-IP-Address = 192.168.2.42 (2) NAS-Identifier = 'cisco-wlc' (2) Airespace-Wlan-Id = 6 (2) Service-Type = Framed-User (2) Framed-MTU = 1300 (2) NAS-Port-Type = Wireless-802.11 (2) EAP-Message = 0x020300980d800000008e1603010089010000850301589c70aac65ee802893e9669a76d4b6ba5245eadb48ca2b02ca1a2c67e308b5e00004a00ffc024c023c00ac009c007c008c028c027c014c013c011c012c026c025c02ac029c005c004c002c003c00fc00ec00cc00d003d003c002f000500040035000a0067006b00330039001601000012000a00080006001700180019000b00020100 (2) State = 0x3286ec463385e1a3af3e7e5c1128d5dc (2) Message-Authenticator = 0x544710c4d800cca04ae8d70b22ba2bd6 (2) # Executing section authorize from file /etc/raddb/sites-enabled/default (2) authorize { (2) filter_username filter_username { (2) if (!&User-Name) (2) if (!&User-Name) -> FALSE (2) if (&User-Name =~ / /) (2) if (&User-Name =~ / /) -> FALSE (2) if (&User-Name =~ /@.*@/ ) (2) if (&User-Name =~ /@.*@/ ) -> FALSE (2) if (&User-Name =~ /\\.\\./ ) (2) if (&User-Name =~ /\\.\\./ ) -> FALSE (2) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (2) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (2) if (&User-Name =~ /\\.$/) (2) if (&User-Name =~ /\\.$/) -> FALSE (2) if (&User-Name =~ /(a)\\./) (2) if (&User-Name =~ /(a)\\./) -> FALSE (2) } # filter_username filter_username = notfound (2) [preprocess] = ok (2) [chap] = noop (2) [mschap] = noop (2) [digest] = noop (2) suffix : Checking for suffix after "@" (2) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (2) suffix : No such realm "NULL" (2) [suffix] = noop (2) if (Called-Station-ID =~ /:test$/) (2) if (Called-Station-ID =~ /:test$/) -> TRUE (2) if (Called-Station-ID =~ /:test$/) { (2) eap : Peer sent code Response (2) ID 3 length 152 (2) eap : No EAP Start, assuming it's an on-going EAP conversation (2) [eap] = updated (2) [files] = noop (2) [maclist] = ok (2) if (notfound) (2) if (notfound) -> FALSE (2) if ( mac-add !~ /%{Calling-Station-ID}/i) (2) EXPAND %{Calling-Station-ID} (2) --> 00236c94ed49 (2) ERROR: Failed retrieving values required to evaluate condition (2) } # if (Called-Station-ID =~ /:test$/) = updated (2) [files] = noop (2) [expiration] = noop (2) [logintime] = noop (2) [pap] = noop (2) } # authorize = updated (2) Found Auth-Type = EAP (2) # Executing group from file /etc/raddb/sites-enabled/default (2) authenticate { (2) eap : Expiring EAP session with state 0x3286ec463385e1a3 (2) eap : Finished EAP session with state 0x3286ec463385e1a3 (2) eap : Previous EAP request found for state 0x3286ec463385e1a3, released from the list (2) eap : Peer sent method TLS (13) (2) eap : EAP TLS (13) (2) eap : Calling eap_tls to process EAP data (2) eap_tls : Authenticate (2) eap_tls : processing EAP-TLS TLS Length 142 (2) eap_tls : Length Included (2) eap_tls : eaptls_verify returned 11 (2) eap_tls : (other): before/accept initialization (2) eap_tls : TLS_accept: before/accept initialization (2) eap_tls : <<< TLS 1.0 Handshake [length 0089], ClientHello (2) eap_tls : TLS_accept: SSLv3 read client hello A (2) eap_tls : >>> TLS 1.0 Handshake [length 0059], ServerHello (2) eap_tls : TLS_accept: SSLv3 write server hello A (2) eap_tls : >>> TLS 1.0 Handshake [length 07aa], Certificate (2) eap_tls : TLS_accept: SSLv3 write certificate A (2) eap_tls : >>> TLS 1.0 Handshake [length 014b], ServerKeyExchange (2) eap_tls : TLS_accept: SSLv3 write key exchange A (2) eap_tls : >>> TLS 1.0 Handshake [length 00b9], CertificateRequest (2) eap_tls : TLS_accept: SSLv3 write certificate request A (2) eap_tls : TLS_accept: SSLv3 flush data (2) eap_tls : TLS_accept: Need to read more data: SSLv3 read client certificate A In SSL Handshake Phase In SSL Accept mode (2) eap_tls : eaptls_process returned 13 (2) eap : New EAP session, adding 'State' attribute to reply 0x3286ec463082e1a3 (2) [eap] = handled (2) } # authenticate = handled (2) Sending Access-Challenge packet to host 192.168.2.42 port 32769, id=18, length=0 (2) EAP-Message = 0x010403ec0dc000000a1b1603010059020000550301589c70a92d3e8d6b590e516b0aa7cb43a4bce8de31334b620f07e5f22ac9e194204fc06eb146aaf33925cb9503d38e0aed4ef2b6b082f6e485abf50ceb399cae73c01400000dff01000100000b00040300010216030107aa0b0007a60007a30003cf308203cb308202b3a003020102020101300d06092a864886f70d01010405003081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d4341301e170d3038303830353131333532335a170d3138303830333131333532335a308191310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d62657267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f676965310f300d060355040313064d61633333393125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e646530820122 (2) Message-Authenticator = 0x00000000000000000000000000000000 (2) State = 0x3286ec463082e1a3af3e7e5c1128d5dc Sending Access-Challenge Id 18 from 10.1.2.27:1812 to 192.168.2.42:32769 EAP-Message = 0x010403ec0dc000000a1b1603010059020000550301589c70a92d3e8d6b590e516b0aa7cb43a4bce8de31334b620f07e5f22ac9e194204fc06eb146aaf33925cb9503d38e0aed4ef2b6b082f6e485abf50ceb399cae73c01400000dff01000100000b00040300010216030107aa0b0007a60007a30003cf308203cb308202b3a003020102020101300d06092a864886f70d01010405003081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d4341301e170d3038303830353131333532335a170d3138303830333131333532335a308191310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d62657267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f676965310f300d060355040313064d61633333393125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653082012 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3286ec463082e1a3af3e7e5c1128d5dc (2) Finished request Waking up in 0.2 seconds. Received Access-Request Id 19 from 192.168.2.42:32769 to 10.1.2.27:1812 length 252 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x020400060d00 State = 0x3286ec463082e1a3af3e7e5c1128d5dc Message-Authenticator = 0x0d72f1f75ef029f407528b3aeefb8e35 (3) Received Access-Request packet from host 192.168.2.42 port 32769, id=19, length=252 (3) User-Name = 'Mac284-1' (3) Chargeable-User-Identity = 0x00 (3) Location-Capable = Civix-Location (3) Calling-Station-Id = '00236c94ed49' (3) Called-Station-Id = '34a84edc9b40:test' (3) NAS-Port = 1 (3) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (3) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (3) NAS-IP-Address = 192.168.2.42 (3) NAS-Identifier = 'cisco-wlc' (3) Airespace-Wlan-Id = 6 (3) Service-Type = Framed-User (3) Framed-MTU = 1300 (3) NAS-Port-Type = Wireless-802.11 (3) EAP-Message = 0x020400060d00 (3) State = 0x3286ec463082e1a3af3e7e5c1128d5dc (3) Message-Authenticator = 0x0d72f1f75ef029f407528b3aeefb8e35 (3) # Executing section authorize from file /etc/raddb/sites-enabled/default (3) authorize { (3) filter_username filter_username { (3) if (!&User-Name) (3) if (!&User-Name) -> FALSE (3) if (&User-Name =~ / /) (3) if (&User-Name =~ / /) -> FALSE (3) if (&User-Name =~ /@.*@/ ) (3) if (&User-Name =~ /@.*@/ ) -> FALSE (3) if (&User-Name =~ /\\.\\./ ) (3) if (&User-Name =~ /\\.\\./ ) -> FALSE (3) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (3) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (3) if (&User-Name =~ /\\.$/) (3) if (&User-Name =~ /\\.$/) -> FALSE (3) if (&User-Name =~ /(a)\\./) (3) if (&User-Name =~ /(a)\\./) -> FALSE (3) } # filter_username filter_username = notfound (3) [preprocess] = ok (3) [chap] = noop (3) [mschap] = noop (3) [digest] = noop (3) suffix : Checking for suffix after "@" (3) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (3) suffix : No such realm "NULL" (3) [suffix] = noop (3) if (Called-Station-ID =~ /:test$/) (3) if (Called-Station-ID =~ /:test$/) -> TRUE (3) if (Called-Station-ID =~ /:test$/) { (3) eap : Peer sent code Response (2) ID 4 length 6 (3) eap : No EAP Start, assuming it's an on-going EAP conversation (3) [eap] = updated (3) [files] = noop (3) [maclist] = ok (3) if (notfound) (3) if (notfound) -> FALSE (3) if ( mac-add !~ /%{Calling-Station-ID}/i) (3) EXPAND %{Calling-Station-ID} (3) --> 00236c94ed49 (3) ERROR: Failed retrieving values required to evaluate condition (3) } # if (Called-Station-ID =~ /:test$/) = updated (3) [files] = noop (3) [expiration] = noop (3) [logintime] = noop (3) [pap] = noop (3) } # authorize = updated (3) Found Auth-Type = EAP (3) # Executing group from file /etc/raddb/sites-enabled/default (3) authenticate { (3) eap : Expiring EAP session with state 0x3286ec463082e1a3 (3) eap : Finished EAP session with state 0x3286ec463082e1a3 (3) eap : Previous EAP request found for state 0x3286ec463082e1a3, released from the list (3) eap : Peer sent method TLS (13) (3) eap : EAP TLS (13) (3) eap : Calling eap_tls to process EAP data (3) eap_tls : Authenticate (3) eap_tls : processing EAP-TLS (3) eap_tls : Received TLS ACK (3) eap_tls : Received TLS ACK (3) eap_tls : ACK handshake fragment handler (3) eap_tls : eaptls_verify returned 1 (3) eap_tls : eaptls_process returned 13 (3) eap : New EAP session, adding 'State' attribute to reply 0x3286ec463183e1a3 (3) [eap] = handled (3) } # authenticate = handled (3) Sending Access-Challenge packet to host 192.168.2.42 port 32769, id=19, length=0 (3) EAP-Message = 0x010503ec0dc000000a1b6a659dcfe39398cfd9c7305d004b5811091a9b899ee9b7a4819aa20ec49b1337c6e1d01b9e96cebec78d7126da71b4cf35fa43ea2403b4ee8b9e4977e98fd081db3845bc77321dfad9c86cfbb4e8802c3600d406856db21e19d20003ce308203ca308202b202090098b6bd1078043d7c300d06092a864886f70d01010405003081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d4341301e170d3038303830353131333531305a170d3138303830333131333531305a3081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949 (3) Message-Authenticator = 0x00000000000000000000000000000000 (3) State = 0x3286ec463183e1a3af3e7e5c1128d5dc Sending Access-Challenge Id 19 from 10.1.2.27:1812 to 192.168.2.42:32769 EAP-Message = 0x010503ec0dc000000a1b6a659dcfe39398cfd9c7305d004b5811091a9b899ee9b7a4819aa20ec49b1337c6e1d01b9e96cebec78d7126da71b4cf35fa43ea2403b4ee8b9e4977e98fd081db3845bc77321dfad9c86cfbb4e8802c3600d406856db21e19d20003ce308203ca308202b202090098b6bd1078043d7c300d06092a864886f70d01010405003081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d4341301e170d3038303830353131333531305a170d3138303830333131333531305a3081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d50494 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3286ec463183e1a3af3e7e5c1128d5dc (3) Finished request Waking up in 0.2 seconds. Received Access-Request Id 20 from 192.168.2.42:32769 to 10.1.2.27:1812 length 252 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x020500060d00 State = 0x3286ec463183e1a3af3e7e5c1128d5dc Message-Authenticator = 0x4cc5f7ef51a86729426329df7f3dcabd (4) Received Access-Request packet from host 192.168.2.42 port 32769, id=20, length=252 (4) User-Name = 'Mac284-1' (4) Chargeable-User-Identity = 0x00 (4) Location-Capable = Civix-Location (4) Calling-Station-Id = '00236c94ed49' (4) Called-Station-Id = '34a84edc9b40:test' (4) NAS-Port = 1 (4) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (4) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (4) NAS-IP-Address = 192.168.2.42 (4) NAS-Identifier = 'cisco-wlc' (4) Airespace-Wlan-Id = 6 (4) Service-Type = Framed-User (4) Framed-MTU = 1300 (4) NAS-Port-Type = Wireless-802.11 (4) EAP-Message = 0x020500060d00 (4) State = 0x3286ec463183e1a3af3e7e5c1128d5dc (4) Message-Authenticator = 0x4cc5f7ef51a86729426329df7f3dcabd (4) # Executing section authorize from file /etc/raddb/sites-enabled/default (4) authorize { (4) filter_username filter_username { (4) if (!&User-Name) (4) if (!&User-Name) -> FALSE (4) if (&User-Name =~ / /) (4) if (&User-Name =~ / /) -> FALSE (4) if (&User-Name =~ /@.*@/ ) (4) if (&User-Name =~ /@.*@/ ) -> FALSE (4) if (&User-Name =~ /\\.\\./ ) (4) if (&User-Name =~ /\\.\\./ ) -> FALSE (4) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (4) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (4) if (&User-Name =~ /\\.$/) (4) if (&User-Name =~ /\\.$/) -> FALSE (4) if (&User-Name =~ /(a)\\./) (4) if (&User-Name =~ /(a)\\./) -> FALSE (4) } # filter_username filter_username = notfound (4) [preprocess] = ok (4) [chap] = noop (4) [mschap] = noop (4) [digest] = noop (4) suffix : Checking for suffix after "@" (4) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (4) suffix : No such realm "NULL" (4) [suffix] = noop (4) if (Called-Station-ID =~ /:test$/) (4) if (Called-Station-ID =~ /:test$/) -> TRUE (4) if (Called-Station-ID =~ /:test$/) { (4) eap : Peer sent code Response (2) ID 5 length 6 (4) eap : No EAP Start, assuming it's an on-going EAP conversation (4) [eap] = updated (4) [files] = noop (4) [maclist] = ok (4) if (notfound) (4) if (notfound) -> FALSE (4) if ( mac-add !~ /%{Calling-Station-ID}/i) (4) EXPAND %{Calling-Station-ID} (4) --> 00236c94ed49 (4) ERROR: Failed retrieving values required to evaluate condition (4) } # if (Called-Station-ID =~ /:test$/) = updated (4) [files] = noop (4) [expiration] = noop (4) [logintime] = noop (4) [pap] = noop (4) } # authorize = updated (4) Found Auth-Type = EAP (4) # Executing group from file /etc/raddb/sites-enabled/default (4) authenticate { (4) eap : Expiring EAP session with state 0x3286ec463183e1a3 (4) eap : Finished EAP session with state 0x3286ec463183e1a3 (4) eap : Previous EAP request found for state 0x3286ec463183e1a3, released from the list (4) eap : Peer sent method TLS (13) (4) eap : EAP TLS (13) (4) eap : Calling eap_tls to process EAP data (4) eap_tls : Authenticate (4) eap_tls : processing EAP-TLS (4) eap_tls : Received TLS ACK (4) eap_tls : Received TLS ACK (4) eap_tls : ACK handshake fragment handler (4) eap_tls : eaptls_verify returned 1 (4) eap_tls : eaptls_process returned 13 (4) eap : New EAP session, adding 'State' attribute to reply 0x3286ec463680e1a3 (4) [eap] = handled (4) } # authenticate = handled (4) Sending Access-Challenge packet to host 192.168.2.42 port 32769, id=20, length=0 (4) EAP-Message = 0x010602610d8000000a1bf72d066d93329bb942fb06862c2fec2ad76c4157b0754ad679a5e2f689478c827dfd4dd53ad9f59f6404326d34ce7ad3d9d4665ea116bd4fedbf129944c8ad1048bfbfb88824da246b160301014b0c0001470300174104c2461c8f32df8e6307d2e4ab9e1d173d34da3585659b3300afbc3546f542d5d7ea943a06eb2bb63df06fd8b87e5a9bf5347f2ec50c3a32ffa96969a6826219d301003c2f99660402340ca595bb777f5a889bfc2ac0443e1e8eafcfc79e09e3a05367243cd8836cf22fed170916ceb56c798273373b0f4cd74ecef5125e2b44c5286b6e11cfc67d0b61044504831e32883d3e1a5b4754555ab3da7f0f8bd34ade833a348de84deb693f03264ecaa21433425ea4cc90b4ef45d550e28fdcc2b326d4621a6b3180fc8e1a29e918aa172df73340dff26aae525b75d5d2c1ba77180ab1be713019fb700c3afa8e73ca37e91a411d35882f2db08085ae20191bcc76d11f7cdf51a2a59308ad90d02cdc77aa1956810982b63dca940584f2f1b57b61ef8329354335824d72515d005f3b73a88846e6bb68c511a2e0b06ab0f595b009303e1916030100b90d0000b10301024000ab00a93081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d30 (4) Message-Authenticator = 0x00000000000000000000000000000000 (4) State = 0x3286ec463680e1a3af3e7e5c1128d5dc Sending Access-Challenge Id 20 from 10.1.2.27:1812 to 192.168.2.42:32769 EAP-Message = 0x010602610d8000000a1bf72d066d93329bb942fb06862c2fec2ad76c4157b0754ad679a5e2f689478c827dfd4dd53ad9f59f6404326d34ce7ad3d9d4665ea116bd4fedbf129944c8ad1048bfbfb88824da246b160301014b0c0001470300174104c2461c8f32df8e6307d2e4ab9e1d173d34da3585659b3300afbc3546f542d5d7ea943a06eb2bb63df06fd8b87e5a9bf5347f2ec50c3a32ffa96969a6826219d301003c2f99660402340ca595bb777f5a889bfc2ac0443e1e8eafcfc79e09e3a05367243cd8836cf22fed170916ceb56c798273373b0f4cd74ecef5125e2b44c5286b6e11cfc67d0b61044504831e32883d3e1a5b4754555ab3da7f0f8bd34ade833a348de84deb693f03264ecaa21433425ea4cc90b4ef45d550e28fdcc2b326d4621a6b3180fc8e1a29e918aa172df73340dff26aae525b75d5d2c1ba77180ab1be713019fb700c3afa8e73ca37e91a411d35882f2db08085ae20191bcc76d11f7cdf51a2a59308ad90d02cdc77aa1956810982b63dca940584f2f1b57b61ef8329354335824d72515d005f3b73a88846e6bb68c511a2e0b06ab0f595b009303e1916030100b90d0000b10301024000ab00a93081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d3 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3286ec463680e1a3af3e7e5c1128d5dc (4) Finished request Waking up in 0.2 seconds. Received Access-Request Id 21 from 192.168.2.42:32769 to 10.1.2.27:1812 length 1532 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x020604fc0dc00000094416030107ae0b0007aa0007a70003d3308203cf308202b7a0030201020202016e300d06092a864886f70d01010405003081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d4341301e170d3135303330343132323032395a170d3137303330333132323032395a308194310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d62657267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653111300f060355040313084d61633238342d313126302406092a864886f70d01090116176275726765727740696d6d756e62696f2e6d70672e646530820122300d06092a864886f70d01010105000382010f003082010a0282010100c7a68bc70a10d04e8ced6f282c2b2d34b377a921a0842d587090342f761117222f0567dafba7bfc025cca4e4eb7f0648ffdc2eb4a068cb063f5c76644 State = 0x3286ec463680e1a3af3e7e5c1128d5dc Message-Authenticator = 0x10295592d7b1c2ce3bb27ca74014847d (5) Received Access-Request packet from host 192.168.2.42 port 32769, id=21, length=1532 (5) User-Name = 'Mac284-1' (5) Chargeable-User-Identity = 0x00 (5) Location-Capable = Civix-Location (5) Calling-Station-Id = '00236c94ed49' (5) Called-Station-Id = '34a84edc9b40:test' (5) NAS-Port = 1 (5) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (5) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (5) NAS-IP-Address = 192.168.2.42 (5) NAS-Identifier = 'cisco-wlc' (5) Airespace-Wlan-Id = 6 (5) Service-Type = Framed-User (5) Framed-MTU = 1300 (5) NAS-Port-Type = Wireless-802.11 (5) EAP-Message = 0x020604fc0dc00000094416030107ae0b0007aa0007a70003d3308203cf308202b7a0030201020202016e300d06092a864886f70d01010405003081a6310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d4341301e170d3135303330343132323032395a170d3137303330333132323032395a308194310b3009060355040613024445311b301906035504081312426164656e2d577565727474656d62657267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653111300f060355040313084d61633238342d313126302406092a864886f70d01090116176275726765727740696d6d756e62696f2e6d70672e646530820122300d06092a864886f70d01010105000382010f003082010a0282010100c7a68bc70a10d04e8ced6f282c2b2d34b377a921a0842d587090342f761117222f0567dafba7bfc025cca4e4eb7f0648ffdc2eb4a068cb063f5c766443 (5) State = 0x3286ec463680e1a3af3e7e5c1128d5dc (5) Message-Authenticator = 0x10295592d7b1c2ce3bb27ca74014847d (5) # Executing section authorize from file /etc/raddb/sites-enabled/default (5) authorize { (5) filter_username filter_username { (5) if (!&User-Name) (5) if (!&User-Name) -> FALSE (5) if (&User-Name =~ / /) (5) if (&User-Name =~ / /) -> FALSE (5) if (&User-Name =~ /@.*@/ ) (5) if (&User-Name =~ /@.*@/ ) -> FALSE (5) if (&User-Name =~ /\\.\\./ ) (5) if (&User-Name =~ /\\.\\./ ) -> FALSE (5) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (5) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (5) if (&User-Name =~ /\\.$/) (5) if (&User-Name =~ /\\.$/) -> FALSE (5) if (&User-Name =~ /(a)\\./) (5) if (&User-Name =~ /(a)\\./) -> FALSE (5) } # filter_username filter_username = notfound (5) [preprocess] = ok (5) [chap] = noop (5) [mschap] = noop (5) [digest] = noop (5) suffix : Checking for suffix after "@" (5) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (5) suffix : No such realm "NULL" (5) [suffix] = noop (5) if (Called-Station-ID =~ /:test$/) (5) if (Called-Station-ID =~ /:test$/) -> TRUE (5) if (Called-Station-ID =~ /:test$/) { (5) eap : Peer sent code Response (2) ID 6 length 1276 (5) eap : No EAP Start, assuming it's an on-going EAP conversation (5) [eap] = updated (5) [files] = noop (5) [maclist] = ok (5) if (notfound) (5) if (notfound) -> FALSE (5) if ( mac-add !~ /%{Calling-Station-ID}/i) (5) EXPAND %{Calling-Station-ID} (5) --> 00236c94ed49 (5) ERROR: Failed retrieving values required to evaluate condition (5) } # if (Called-Station-ID =~ /:test$/) = updated (5) [files] = noop (5) [expiration] = noop (5) [logintime] = noop (5) [pap] = noop (5) } # authorize = updated (5) Found Auth-Type = EAP (5) # Executing group from file /etc/raddb/sites-enabled/default (5) authenticate { (5) eap : Expiring EAP session with state 0x3286ec463680e1a3 (5) eap : Finished EAP session with state 0x3286ec463680e1a3 (5) eap : Previous EAP request found for state 0x3286ec463680e1a3, released from the list (5) eap : Peer sent method TLS (13) (5) eap : EAP TLS (13) (5) eap : Calling eap_tls to process EAP data (5) eap_tls : Authenticate (5) eap_tls : processing EAP-TLS TLS Length 2372 (5) eap_tls : Received EAP-TLS First Fragment of the message (5) eap_tls : eaptls_verify returned 9 (5) eap_tls : eaptls_process returned 13 (5) eap : New EAP session, adding 'State' attribute to reply 0x3286ec463781e1a3 (5) [eap] = handled (5) } # authenticate = handled (5) Sending Access-Challenge packet to host 192.168.2.42 port 32769, id=21, length=0 (5) EAP-Message = 0x010700060d00 (5) Message-Authenticator = 0x00000000000000000000000000000000 (5) State = 0x3286ec463781e1a3af3e7e5c1128d5dc Sending Access-Challenge Id 21 from 10.1.2.27:1812 to 192.168.2.42:32769 EAP-Message = 0x010700060d00 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3286ec463781e1a3af3e7e5c1128d5dc (5) Finished request Waking up in 0.2 seconds. Received Access-Request Id 22 from 192.168.2.42:32769 to 10.1.2.27:1812 length 1366 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x020704580d007565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d434130820122300d06092a864886f70d01010105000382010f003082010a0282010100ddb3cbfbf6c5d677919df998e53625f1a01f7f67f3f2ec6c6c5ccb191b3bf2f8e4f3181a5e19352d9f5d6b9ad47b3572d4f6ea9a950f83c71b883af3701eb83dc7865e128163d7d88010568aa8a577430a4faa87725f1e7cd5f8342cef30e4336358d938add380f3877ed14eb3d9551c76aea9fa556084e64552283d48e23904e4b5490c3273dc168f52ed8c877c2ac37d0bb94ceabaf24954c479d0f65c81c4669f7b7e345f6d9b900b46eafb6dc0ceeeb5e41b5f38e92f9a8f760acf2d8dccb5c21e9f2ff4262e9cde23b5f7372552da398c6e54480f0f86e57e55a90866603edfc5888775d4413085f7786538c6a1cd20e26cb6d62a4f42b08b944292ab350203010001300d06092a864886f70d01010405000382010100101eda3f790f087c8060506e9218801409545d0d275b72add6d46ffb7773caa39450a32462da5829cb13b0a5d256a4b State = 0x3286ec463781e1a3af3e7e5c1128d5dc Message-Authenticator = 0x5876b9620fed3f65ab38aa94397a1e96 (6) Received Access-Request packet from host 192.168.2.42 port 32769, id=22, length=1366 (6) User-Name = 'Mac284-1' (6) Chargeable-User-Identity = 0x00 (6) Location-Capable = Civix-Location (6) Calling-Station-Id = '00236c94ed49' (6) Called-Station-Id = '34a84edc9b40:test' (6) NAS-Port = 1 (6) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (6) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (6) NAS-IP-Address = 192.168.2.42 (6) NAS-Identifier = 'cisco-wlc' (6) Airespace-Wlan-Id = 6 (6) Service-Type = Framed-User (6) Framed-MTU = 1300 (6) NAS-Port-Type = Wireless-802.11 (6) EAP-Message = 0x020704580d007565727474656d626572673111300f060355040713084672656962757267312d302b060355040a13244d61782d506c616e636b2d496e73746974757420662e20496d6d756e62696f6c6f6769653125302306092a864886f70d010901161673797374656d40696d6d756e62696f2e6d70672e64653111300f060355040313084d504949422d434130820122300d06092a864886f70d01010105000382010f003082010a0282010100ddb3cbfbf6c5d677919df998e53625f1a01f7f67f3f2ec6c6c5ccb191b3bf2f8e4f3181a5e19352d9f5d6b9ad47b3572d4f6ea9a950f83c71b883af3701eb83dc7865e128163d7d88010568aa8a577430a4faa87725f1e7cd5f8342cef30e4336358d938add380f3877ed14eb3d9551c76aea9fa556084e64552283d48e23904e4b5490c3273dc168f52ed8c877c2ac37d0bb94ceabaf24954c479d0f65c81c4669f7b7e345f6d9b900b46eafb6dc0ceeeb5e41b5f38e92f9a8f760acf2d8dccb5c21e9f2ff4262e9cde23b5f7372552da398c6e54480f0f86e57e55a90866603edfc5888775d4413085f7786538c6a1cd20e26cb6d62a4f42b08b944292ab350203010001300d06092a864886f70d01010405000382010100101eda3f790f087c8060506e9218801409545d0d275b72add6d46ffb7773caa39450a32462da5829cb13b0a5d256a4b9 (6) State = 0x3286ec463781e1a3af3e7e5c1128d5dc (6) Message-Authenticator = 0x5876b9620fed3f65ab38aa94397a1e96 (6) # Executing section authorize from file /etc/raddb/sites-enabled/default (6) authorize { (6) filter_username filter_username { (6) if (!&User-Name) (6) if (!&User-Name) -> FALSE (6) if (&User-Name =~ / /) (6) if (&User-Name =~ / /) -> FALSE (6) if (&User-Name =~ /@.*@/ ) (6) if (&User-Name =~ /@.*@/ ) -> FALSE (6) if (&User-Name =~ /\\.\\./ ) (6) if (&User-Name =~ /\\.\\./ ) -> FALSE (6) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (6) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (6) if (&User-Name =~ /\\.$/) (6) if (&User-Name =~ /\\.$/) -> FALSE (6) if (&User-Name =~ /(a)\\./) (6) if (&User-Name =~ /(a)\\./) -> FALSE (6) } # filter_username filter_username = notfound (6) [preprocess] = ok (6) [chap] = noop (6) [mschap] = noop (6) [digest] = noop (6) suffix : Checking for suffix after "@" (6) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (6) suffix : No such realm "NULL" (6) [suffix] = noop (6) if (Called-Station-ID =~ /:test$/) (6) if (Called-Station-ID =~ /:test$/) -> TRUE (6) if (Called-Station-ID =~ /:test$/) { (6) eap : Peer sent code Response (2) ID 7 length 1112 (6) eap : No EAP Start, assuming it's an on-going EAP conversation (6) [eap] = updated (6) [files] = noop (6) [maclist] = ok (6) if (notfound) (6) if (notfound) -> FALSE (6) if ( mac-add !~ /%{Calling-Station-ID}/i) (6) EXPAND %{Calling-Station-ID} (6) --> 00236c94ed49 (6) ERROR: Failed retrieving values required to evaluate condition (6) } # if (Called-Station-ID =~ /:test$/) = updated (6) [files] = noop (6) [expiration] = noop (6) [logintime] = noop (6) [pap] = noop (6) } # authorize = updated (6) Found Auth-Type = EAP (6) # Executing group from file /etc/raddb/sites-enabled/default (6) authenticate { (6) eap : Expiring EAP session with state 0x3286ec463781e1a3 (6) eap : Finished EAP session with state 0x3286ec463781e1a3 (6) eap : Previous EAP request found for state 0x3286ec463781e1a3, released from the list (6) eap : Peer sent method TLS (13) (6) eap : EAP TLS (13) (6) eap : Calling eap_tls to process EAP data (6) eap_tls : Authenticate (6) eap_tls : processing EAP-TLS (6) eap_tls : eaptls_verify returned 7 (6) eap_tls : Done initial handshake (6) eap_tls : <<< TLS 1.0 Handshake [length 07ae], Certificate (6) eap_tls : chain-depth=1, (6) eap_tls : error=0 (6) eap_tls : --> User-Name = Mac284-1 (6) eap_tls : --> BUF-Name = ###########blurred out########### (6) eap_tls : --> subject = /C=DE/ST=###########blurred out########### (6) eap_tls : --> issuer = /C=DE/ST=###########blurred out########### (6) eap_tls : --> verify return:1 (6) eap_tls : chain-depth=0, (6) eap_tls : error=0 (6) eap_tls : --> User-Name = Mac284-1 (6) eap_tls : --> BUF-Name = Mac284-1 (6) eap_tls : --> subject = /C=DE/ST=###########blurred out########### (6) eap_tls : --> issuer = /C=DE/ST=###########blurred out########### (6) eap_tls : --> verify return:1 (6) eap_tls : TLS_accept: SSLv3 read client certificate A (6) eap_tls : <<< TLS 1.0 Handshake [length 0046], ClientKeyExchange (6) eap_tls : TLS_accept: SSLv3 read client key exchange A (6) eap_tls : <<< TLS 1.0 Handshake [length 0106], CertificateVerify (6) eap_tls : TLS_accept: SSLv3 read certificate verify A (6) eap_tls : <<< TLS 1.0 ChangeCipherSpec [length 0001] (6) eap_tls : <<< TLS 1.0 Handshake [length 0010], Finished (6) eap_tls : TLS_accept: SSLv3 read finished A (6) eap_tls : >>> TLS 1.0 ChangeCipherSpec [length 0001] (6) eap_tls : TLS_accept: SSLv3 write change cipher spec A (6) eap_tls : >>> TLS 1.0 Handshake [length 0010], Finished (6) eap_tls : TLS_accept: SSLv3 write finished A (6) eap_tls : TLS_accept: SSLv3 flush data SSL: adding session 4fc06eb146aaf33925cb9503d38e0aed4ef2b6b082f6e485abf50ceb399cae73 to cache (6) eap_tls : (other): SSL negotiation finished successfully SSL Connection Established (6) eap_tls : eaptls_process returned 13 (6) eap : New EAP session, adding 'State' attribute to reply 0x3286ec46348ee1a3 (6) [eap] = handled (6) } # authenticate = handled (6) Sending Access-Challenge packet to host 192.168.2.42 port 32769, id=22, length=0 (6) EAP-Message = 0x010800450d800000003b1403010001011603010030dfca648e3d3d11e62b70f33686cc777c4725cbaf9ac64a187585afb0e0e9a1ab552d1d9477d6287a5ab1eb57aa8cfc90 (6) Message-Authenticator = 0x00000000000000000000000000000000 (6) State = 0x3286ec46348ee1a3af3e7e5c1128d5dc Sending Access-Challenge Id 22 from 10.1.2.27:1812 to 192.168.2.42:32769 EAP-Message = 0x010800450d800000003b1403010001011603010030dfca648e3d3d11e62b70f33686cc777c4725cbaf9ac64a187585afb0e0e9a1ab552d1d9477d6287a5ab1eb57aa8cfc90 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3286ec46348ee1a3af3e7e5c1128d5dc (6) Finished request Waking up in 0.2 seconds. Received Access-Request Id 23 from 192.168.2.42:32769 to 10.1.2.27:1812 length 252 User-Name = 'Mac284-1' Chargeable-User-Identity = 0x00 Location-Capable = Civix-Location Calling-Station-Id = '00236c94ed49' Called-Station-Id = '34a84edc9b40:test' NAS-Port = 1 Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' NAS-IP-Address = 192.168.2.42 NAS-Identifier = 'cisco-wlc' Airespace-Wlan-Id = 6 Service-Type = Framed-User Framed-MTU = 1300 NAS-Port-Type = Wireless-802.11 EAP-Message = 0x020800060d00 State = 0x3286ec46348ee1a3af3e7e5c1128d5dc Message-Authenticator = 0xe683920c6f081c2299ff1364520a77a0 (7) Received Access-Request packet from host 192.168.2.42 port 32769, id=23, length=252 (7) User-Name = 'Mac284-1' (7) Chargeable-User-Identity = 0x00 (7) Location-Capable = Civix-Location (7) Calling-Station-Id = '00236c94ed49' (7) Called-Station-Id = '34a84edc9b40:test' (7) NAS-Port = 1 (7) Cisco-AVPair = 'audit-session-id=c0a8022a0006da17589c709a' (7) Acct-Session-Id = '589c709a/00:23:6c:94:ed:49/89377' (7) NAS-IP-Address = 192.168.2.42 (7) NAS-Identifier = 'cisco-wlc' (7) Airespace-Wlan-Id = 6 (7) Service-Type = Framed-User (7) Framed-MTU = 1300 (7) NAS-Port-Type = Wireless-802.11 (7) EAP-Message = 0x020800060d00 (7) State = 0x3286ec46348ee1a3af3e7e5c1128d5dc (7) Message-Authenticator = 0xe683920c6f081c2299ff1364520a77a0 (7) # Executing section authorize from file /etc/raddb/sites-enabled/default (7) authorize { (7) filter_username filter_username { (7) if (!&User-Name) (7) if (!&User-Name) -> FALSE (7) if (&User-Name =~ / /) (7) if (&User-Name =~ / /) -> FALSE (7) if (&User-Name =~ /@.*@/ ) (7) if (&User-Name =~ /@.*@/ ) -> FALSE (7) if (&User-Name =~ /\\.\\./ ) (7) if (&User-Name =~ /\\.\\./ ) -> FALSE (7) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) (7) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\\.(.+)$/)) -> FALSE (7) if (&User-Name =~ /\\.$/) (7) if (&User-Name =~ /\\.$/) -> FALSE (7) if (&User-Name =~ /(a)\\./) (7) if (&User-Name =~ /(a)\\./) -> FALSE (7) } # filter_username filter_username = notfound (7) [preprocess] = ok (7) [chap] = noop (7) [mschap] = noop (7) [digest] = noop (7) suffix : Checking for suffix after "@" (7) suffix : No '@' in User-Name = "Mac284-1", looking up realm NULL (7) suffix : No such realm "NULL" (7) [suffix] = noop (7) if (Called-Station-ID =~ /:test$/) (7) if (Called-Station-ID =~ /:test$/) -> TRUE (7) if (Called-Station-ID =~ /:test$/) { (7) eap : Peer sent code Response (2) ID 8 length 6 (7) eap : No EAP Start, assuming it's an on-going EAP conversation (7) [eap] = updated (7) [files] = noop (7) [maclist] = ok (7) if (notfound) (7) if (notfound) -> FALSE (7) if ( mac-add !~ /%{Calling-Station-ID}/i) (7) EXPAND %{Calling-Station-ID} (7) --> 00236c94ed49 (7) ERROR: Failed retrieving values required to evaluate condition (7) } # if (Called-Station-ID =~ /:test$/) = updated (7) [files] = noop (7) [expiration] = noop (7) [logintime] = noop (7) [pap] = noop (7) } # authorize = updated (7) Found Auth-Type = EAP (7) # Executing group from file /etc/raddb/sites-enabled/default (7) authenticate { (7) eap : Expiring EAP session with state 0x3286ec46348ee1a3 (7) eap : Finished EAP session with state 0x3286ec46348ee1a3 (7) eap : Previous EAP request found for state 0x3286ec46348ee1a3, released from the list (7) eap : Peer sent method TLS (13) (7) eap : EAP TLS (13) (7) eap : Calling eap_tls to process EAP data (7) eap_tls : Authenticate (7) eap_tls : processing EAP-TLS (7) eap_tls : Received TLS ACK (7) eap_tls : Received TLS ACK (7) eap_tls : ACK handshake is finished (7) eap_tls : eaptls_verify returned 3 (7) eap_tls : eaptls_process returned 3 (7) eap_tls : Saving session 4fc06eb146aaf33925cb9503d38e0aed4ef2b6b082f6e485abf50ceb399cae73 vps 0x7f11ef620680 in the cache (7) eap : Freeing handler (7) [eap] = ok (7) } # authenticate = ok (7) Login OK: [Mac284-1] (from client cisco-wlc port 1 cli 00236c94ed49) (7) # Executing section post-auth from file /etc/raddb/sites-enabled/default (7) post-auth { (7) [exec] = noop (7) remove_reply_message_if_eap remove_reply_message_if_eap { (7) if (&reply:EAP-Message && &reply:Reply-Message) (7) if (&reply:EAP-Message && &reply:Reply-Message) -> FALSE (7) else else { (7) [noop] = noop (7) } # else else = noop (7) } # remove_reply_message_if_eap remove_reply_message_if_eap = noop (7) } # post-auth = noop (7) Sending Access-Accept packet to host 192.168.2.42 port 32769, id=23, length=0 (7) MS-MPPE-Recv-Key = 0x524620c62a30b50ced129eb501237962ea4113db5e897220560b8685fa5901e2 (7) MS-MPPE-Send-Key = 0x891949b902c55410351d498c2eb18db6cbe6832859c4b2dee3fa087ff2a0c248 (7) EAP-MSK = 0x524620c62a30b50ced129eb501237962ea4113db5e897220560b8685fa5901e2891949b902c55410351d498c2eb18db6cbe6832859c4b2dee3fa087ff2a0c248 (7) EAP-EMSK = 0x880ef79921478e5bd23d143295c03af31d8a2c9bf7c7abbed6709b413df36c5224299e1d257d9309cdf9d970d522e2e97ac7f37b27b63ffe15c1d342ba8562d3 (7) EAP-Session-Id = 0x0d589c70aac65ee802893e9669a76d4b6ba5245eadb48ca2b02ca1a2c67e308b5e589c70a92d3e8d6b590e516b0aa7cb43a4bce8de31334b620f07e5f22ac9e194 (7) EAP-Message = 0x03080004 (7) Message-Authenticator = 0x00000000000000000000000000000000 (7) User-Name = 'Mac284-1' Sending Access-Accept Id 23 from 10.1.2.27:1812 to 192.168.2.42:32769 MS-MPPE-Recv-Key = 0x524620c62a30b50ced129eb501237962ea4113db5e897220560b8685fa5901e2 MS-MPPE-Send-Key = 0x891949b902c55410351d498c2eb18db6cbe6832859c4b2dee3fa087ff2a0c248 EAP-Message = 0x03080004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = 'Mac284-1' (7) Finished request Waking up in 0.2 seconds. Waking up in 4.5 seconds. ^C [root@radrad raddb]#

1 0

3.0.12 spec file for RHEL?
by David Hartburn 09 Feb '17

09 Feb '17

Can anyone recommend where to get hold of a freeradius.spec file to build 3.0.12 RPMs in the same way as RHEL do (only much newer!)? For 3.0.11, I used the source & spec package at: https://koji.fedoraproject.org/koji/packageinfo?packageID=298 Thanks Dave

5 7

Problem setting eduroam
by Olivier 09 Feb '17

09 Feb '17

Hi, I have some problems setting up eduroam. I am in charge of CS department inside my university. I have credential both in CS department and at the university level. I can use my CS credential to authenticate to eduroam, whether I am in my CS department network or anywhere else in the university network (and in the world according to some roaming users). I can use my university credentials to authenticate to eduroam when I am on the university network, but it does not work when I am in CS department network. I don't know if it is working anywhere else in the world. CS department network and university network are not connected, futhermore they both refer to the national eduroam gateway for authentication (for historical reason, there is no hierarchy). How can I check why I cannot authenticate? It could be 2 reasons: - I do not proxy properly to the national eduroam gateway - my university side of eduroam is not receiving/answering to the authenication requests coming from upper level. I have no credential outside of my CS department and university that I can use for testing. Best regards, Olivier --

4 5

Log final reply packet including any VSA's
by Arnab Roy 08 Feb '17

08 Feb '17

Hi , Just wanted to check if their are any ways of logging the final reply sent to NAS. By this I mean this bit from radiusd -X (10) Sent Access-Accept Id 10 from 172.21.8.201:1812 to 172.20.0.76:39604 length 0 (10) Aruba-User-Role = "authenticated" (10) MS-MPPE-Recv-Key = 0xf2af9bba1de508511fa7ed2d41fdcef297593e51a2901c8d90e64889345c4190 (10) MS-MPPE-Send-Key = 0xf522b78b422d7a5a23302b3370fce4a080e8cad27e83391f220645ca8f269f46 (10) EAP-Message = 0x030a0004 (10) Message-Authenticator = 0x00000000000000000000000000000000 (10) Finished request In this its sending back an Aruba VSA. I can get all of the above by doing %{reply:[*]} but it doesnt log the actual VSA in it. Just checking if there is an easy way of doing. I need both the name of the attribute and the actual attribute value returned like its showing up on this debug. Many Thanks Arnab

2 2

Multiple Interfaces, Not Seeing Requests
by Matthew West 07 Feb '17

07 Feb '17

Hello FreeRADIUS List, Thank you for taking the time to help me troubleshoot an issue: the radiusd service on a server with two network interfaces is not receiving RADIUS requests from network. When requests are made locally using radtest and eapol_test (with the EAP-MSCHAPv2 file,) the service responds just fine. I've done both packet-capture on the local router and a tcp dump showing packets arriving on the server. My guess is that the service isn't listening to the correct interface, but it is configured to listen to all interfaces (from what I can tell). Environment: * radiusd: FreeRADIUS Version 3.0.4, for host x86_64-redhat-linux-gnu * CentOS Linux release 7.3.1611 (Core) * Network adapters: - ens32: 172.16.xxx.253 - used for management of server - ens33: 10.xxx.xxx.253 - used for RADIUS requests radiusd -X Startup: # radiusd -X radiusd: FreeRADIUS Version 3.0.4, for host x86_64-redhat-linux-gnu, built on Jan 17 2017 at 18:49:55 ... Listening on auth address * port 1812 as server default Listening on acct address * port 1813 as server default Listening on auth address :: port 1812 as server default Listening on acct address :: port 1813 as server default Listening on auth address 127.0.0.1 port 18120 as server inner-tunnel Opening new proxy socket 'proxy address * port 0' Listening on proxy address * port 35952 Ready to process requests Proof packet is arriving: 23:20:50.935823 IP 10.xxx.xxx.205.1645 > 10.xxx.xxx.253.1812: RADIUS, Access Request (1), id: 0x55 length: 49 In clients.conf: client NSO-ACCESS-SWI { ipaddr = 10.xxx.xxx.205 secret = testing123 } I'm happy to do the legwork for this one. Can someone point me in the right direction for further troubleshooting? Much Thanks, Matthew West

3 9

Re: Problem making certs (Alan DeKok)
by Peter Hutchison 07 Feb '17

07 Feb '17

>Hello: >I'm new to Freeradius and to Linux (Ubuntu Server 16.04) as well. I've got my first server up and running Freeradius v3 and can >successfully authorize users on my network with a username and password. Works great and was easy to do. My AXis ipCCTV cameras >require EAP-TLS and this is where I'm stuck. >My clients require a CA Certificate, a Client cert and private key, an EAP identity and Private key password for the EAP >identity. I'm not exactly sure where all this comes from. >I can't get "make" or "make client" to do anything other than give me a "No targets specified and no makefile found" error. >How do I complete the jump to EAP-TLS? Any guides online? You need to create a Certificate request file (CSR) to a public Certificate Authority e.g. GlobalSign, Comodo, GoDaddy, etc, , this will also create your Private key file. You can use Open SSL to generate the CSR and private key. Once you have submitted your request file, the CA will proviate you will a certificate and a copy of their root certificates to go with it. Then you can place your certificate files on your radius server in your freeradius/certs folder. I prefer to have the private, public and root files concatenated together e.g. myeapcertificate.pem. Then update your eap.conf file and point the private_key_file entry and certificate_file entry to the same concatenated file. Peter Hutchison MCP Senior Network Systems Specialist * 01484 473716 Networks Team University of Huddersfield | Queensgate | Huddersfield | HD1 3DH University of Huddersfield inspiring tomorrow's professionals. [http://marketing.hud.ac.uk/_HOSTED/EmailSig2014/EmailSigFooter.jpg] This transmission is confidential and may be legally privileged. If you receive it in error, please notify us immediately by e-mail and remove it from your system. If the content of this e-mail does not relate to the business of the University of Huddersfield, then we do not endorse it and will accept no liability.

2 1

RLM_Perl Question
by Arnab Roy 07 Feb '17

07 Feb '17

Hi All, This is probably a rather easy one and I am certainly overlooking something simple somewhere. I trying to write out if the request was accepted or rejected using RLM perl into a json file. I am able to get everything under the sun for the request apart from this simple thing. Looking at how this is done in the sql module it appears its just a simple expansion of %{reply:Packet-Type}, but cant find a way to do the same in RLM perl. Thanks in advance. Many Thanks Arnab

2 3

The Class attributed is missing in some accounting packets sent from the same NAS.
by Selahattin Cilek 07 Feb '17

07 Feb '17

I have been experimenting with the Class attribute to obtain the user's true identity in order to do accounting and I realised that accounting packets arriving from some users do not have this attribute. User DIALLO is one: # tcpdump -n -vvv -i igb0_vlan1 src host 192.168.0.38 and dst host 192.168.0.1 and dst port 1813 tcpdump: listening on igb0_vlan1, link-type EN10MB (Ethernet), capture size 65535 bytes 18:53:55.223923 IP (tos 0x0, ttl 64, id 11875, offset 0, flags [none], proto UDP (17), length 239) 192.168.0.38.58259 > 192.168.0.1.1813: [udp sum ok] RADIUS, length: 211 Accounting Request (4), id: 0x23, Authenticator: 665d040e38bcb72f122fdd6dcd9276de Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000067 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3036 0x0010: 37 Accounting Status Attribute (40), length: 6, Value: Interim-Update 0x0000: 0000 0003 Accounting Authentication Attribute (45), length: 6, Value: RADIUS 0x0000: 0000 0001 Username Attribute (1), length: 8, Value: DIALLO 0x0000: 4449 414c 4c4f NAS ID Attribute (32), length: 16, Value: KAT_8_MERDIVEN 0x0000: 4b41 545f 385f 4d45 5244 4956 454e Called Station Attribute (30), length: 27, Value: DC-9F-DB-34-CF-B4:TDV.NET 0x0000: 4443 2d39 462d 4442 2d33 342d 4346 2d42 0x0010: 343a 5444 562e 4e45 54 NAS Port Type Attribute (61), length: 6, Value: Wireless - IEEE 802.11 0x0000: 0000 0013 NAS Port Attribute (5), length: 6, Value: 0 0x0000: 0000 0000 Calling Station Attribute (31), length: 19, Value: B0-E0-3C-7D-5F-1C 0x0000: 4230 2d45 302d 3343 2d37 442d 3546 2d31 0x0010: 43 Connect Info Attribute (77), length: 23, Value: CONNECT 0Mbps 802.11b 0x0000: 434f 4e4e 4543 5420 304d 6270 7320 3830 0x0010: 322e 3131 62 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000067 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3036 0x0010: 37 Accounting Session Time Attribute (46), length: 6, Value: 20:00 min 0x0000: 0000 04b0 Accounting Input Packets Attribute (47), length: 6, Value: 824 0x0000: 0000 0338 Accounting Output Packets Attribute (48), length: 6, Value: 609 0x0000: 0000 0261 Accounting Input Octets Attribute (42), length: 6, Value: 122272 0x0000: 0001 dda0 Accounting Output Octets Attribute (43), length: 6, Value: 267901 0x0000: 0004 167d Event Timestamp Attribute (55), length: 6, Value: Tue Feb 7 18:53:55 2017 0x0000: 5899 ed93 18:55:34.027533 IP (tos 0x0, ttl 64, id 11888, offset 0, flags [none], proto UDP (17), length 221) 192.168.0.38.58259 > 192.168.0.1.1813: [udp sum ok] RADIUS, length: 193 Accounting Request (4), id: 0x30, Authenticator: b00abcd321185eb862b8caad7b1978ef Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000075 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 35 Accounting Status Attribute (40), length: 6, Value: Start 0x0000: 0000 0001 Accounting Authentication Attribute (45), length: 6, Value: RADIUS 0x0000: 0000 0001 Username Attribute (1), length: 13, Value: 99709507736 0x0000: 3939 3730 3935 3037 3733 36 NAS ID Attribute (32), length: 16, Value: KAT_8_MERDIVEN 0x0000: 4b41 545f 385f 4d45 5244 4956 454e Called Station Attribute (30), length: 27, Value: DC-9F-DB-34-CF-B4:TDV.NET 0x0000: 4443 2d39 462d 4442 2d33 342d 4346 2d42 0x0010: 343a 5444 562e 4e45 54 NAS Port Type Attribute (61), length: 6, Value: Wireless - IEEE 802.11 0x0000: 0000 0013 NAS Port Attribute (5), length: 6, Value: 0 0x0000: 0000 0000 Calling Station Attribute (31), length: 19, Value: EC-9B-F3-48-BD-70 0x0000: 4543 2d39 422d 4633 2d34 382d 4244 2d37 0x0010: 30 Connect Info Attribute (77), length: 23, Value: CONNECT 0Mbps 802.11b 0x0000: 434f 4e4e 4543 5420 304d 6270 7320 3830 0x0010: 322e 3131 62 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000075 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 35 Class Attribute (25), length: 13, Value: 99709507736 0x0000: 3939 3730 3935 3037 3733 36 18:58:34.913045 IP (tos 0x0, ttl 64, id 11889, offset 0, flags [none], proto UDP (17), length 255) 192.168.0.38.58259 > 192.168.0.1.1813: [udp sum ok] RADIUS, length: 227 Accounting Request (4), id: 0x31, Authenticator: 28c4e93aeb5f31d38dd70b203cc3c397 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-0000006E 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3036 0x0010: 45 Accounting Status Attribute (40), length: 6, Value: Interim-Update 0x0000: 0000 0003 Accounting Authentication Attribute (45), length: 6, Value: RADIUS 0x0000: 0000 0001 Username Attribute (1), length: 11, Value: anonymous 0x0000: 616e 6f6e 796d 6f75 73 NAS ID Attribute (32), length: 16, Value: KAT_8_MERDIVEN 0x0000: 4b41 545f 385f 4d45 5244 4956 454e Called Station Attribute (30), length: 27, Value: DC-9F-DB-34-CF-B4:TDV.NET 0x0000: 4443 2d39 462d 4442 2d33 342d 4346 2d42 0x0010: 343a 5444 562e 4e45 54 NAS Port Type Attribute (61), length: 6, Value: Wireless - IEEE 802.11 0x0000: 0000 0013 NAS Port Attribute (5), length: 6, Value: 0 0x0000: 0000 0000 Calling Station Attribute (31), length: 19, Value: 74-C6-3B-36-29-59 0x0000: 3734 2d43 362d 3342 2d33 362d 3239 2d35 0x0010: 39 Connect Info Attribute (77), length: 23, Value: CONNECT 0Mbps 802.11b 0x0000: 434f 4e4e 4543 5420 304d 6270 7320 3830 0x0010: 322e 3131 62 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-0000006E 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3036 0x0010: 45 Class Attribute (25), length: 13, Value: 99820339216 0x0000: 3939 3832 3033 3339 3231 36 Accounting Session Time Attribute (46), length: 6, Value: 20:00 min 0x0000: 0000 04b0 Accounting Input Packets Attribute (47), length: 6, Value: 86528 0x0000: 0001 5200 Accounting Output Packets Attribute (48), length: 6, Value: 170084 0x0000: 0002 9864 Accounting Input Octets Attribute (42), length: 6, Value: 8723193 0x0000: 0085 1af9 Accounting Output Octets Attribute (43), length: 6, Value: 246200999 0x0000: 0eac baa7 Event Timestamp Attribute (55), length: 6, Value: Tue Feb 7 18:58:34 2017 0x0000: 5899 eeaa 19:03:55.228054 IP (tos 0x0, ttl 64, id 11890, offset 0, flags [none], proto UDP (17), length 239) 192.168.0.38.58259 > 192.168.0.1.1813: [udp sum ok] RADIUS, length: 211 Accounting Request (4), id: 0x32, Authenticator: 8bfa38999488fd0e02b3c2218d27536c Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000067 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3036 0x0010: 37 Accounting Status Attribute (40), length: 6, Value: Interim-Update 0x0000: 0000 0003 Accounting Authentication Attribute (45), length: 6, Value: RADIUS 0x0000: 0000 0001 Username Attribute (1), length: 8, Value: DIALLO 0x0000: 4449 414c 4c4f NAS ID Attribute (32), length: 16, Value: KAT_8_MERDIVEN 0x0000: 4b41 545f 385f 4d45 5244 4956 454e Called Station Attribute (30), length: 27, Value: DC-9F-DB-34-CF-B4:TDV.NET 0x0000: 4443 2d39 462d 4442 2d33 342d 4346 2d42 0x0010: 343a 5444 562e 4e45 54 NAS Port Type Attribute (61), length: 6, Value: Wireless - IEEE 802.11 0x0000: 0000 0013 NAS Port Attribute (5), length: 6, Value: 0 0x0000: 0000 0000 Calling Station Attribute (31), length: 19, Value: B0-E0-3C-7D-5F-1C 0x0000: 4230 2d45 302d 3343 2d37 442d 3546 2d31 0x0010: 43 Connect Info Attribute (77), length: 23, Value: CONNECT 0Mbps 802.11b 0x0000: 434f 4e4e 4543 5420 304d 6270 7320 3830 0x0010: 322e 3131 62 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000067 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3036 0x0010: 37 Accounting Session Time Attribute (46), length: 6, Value: 30:00 min 0x0000: 0000 0708 Accounting Input Packets Attribute (47), length: 6, Value: 1035 0x0000: 0000 040b Accounting Output Packets Attribute (48), length: 6, Value: 720 0x0000: 0000 02d0 Accounting Input Octets Attribute (42), length: 6, Value: 143517 0x0000: 0002 309d Accounting Output Octets Attribute (43), length: 6, Value: 283570 0x0000: 0004 53b2 Event Timestamp Attribute (55), length: 6, Value: Tue Feb 7 19:03:55 2017 0x0000: 5899 efeb 19:04:21.718225 IP (tos 0x0, ttl 64, id 11896, offset 0, flags [none], proto UDP (17), length 208) 192.168.0.38.58259 > 192.168.0.1.1813: [udp sum ok] RADIUS, length: 180 Accounting Request (4), id: 0x38, Authenticator: c8eb8e5b65aaddedfceb8025ceecb296 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000076 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 36 Accounting Status Attribute (40), length: 6, Value: Start 0x0000: 0000 0001 Accounting Authentication Attribute (45), length: 6, Value: RADIUS 0x0000: 0000 0001 Username Attribute (1), length: 13, Value: 11947606194 0x0000: 3131 3934 3736 3036 3139 34 NAS ID Attribute (32), length: 16, Value: KAT_8_MERDIVEN 0x0000: 4b41 545f 385f 4d45 5244 4956 454e Called Station Attribute (30), length: 27, Value: DC-9F-DB-34-CF-B4:TDV.NET 0x0000: 4443 2d39 462d 4442 2d33 342d 4346 2d42 0x0010: 343a 5444 562e 4e45 54 NAS Port Type Attribute (61), length: 6, Value: Wireless - IEEE 802.11 0x0000: 0000 0013 NAS Port Attribute (5), length: 6, Value: 0 0x0000: 0000 0000 Calling Station Attribute (31), length: 19, Value: 9C-5C-F9-4B-C2-E1 0x0000: 3943 2d35 432d 4639 2d34 422d 4332 2d45 0x0010: 31 Connect Info Attribute (77), length: 23, Value: CONNECT 0Mbps 802.11b 0x0000: 434f 4e4e 4543 5420 304d 6270 7320 3830 0x0010: 322e 3131 62 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000076 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 36 And user 11947606194 : 19:05:09.093536 IP (tos 0x0, ttl 64, id 11897, offset 0, flags [none], proto UDP (17), length 250) 192.168.0.38.58259 > 192.168.0.1.1813: [udp sum ok] RADIUS, length: 222 Accounting Request (4), id: 0x39, Authenticator: 0fdcd6d168cbd93aa971cba850d62d21 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000076 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 36 Accounting Status Attribute (40), length: 6, Value: Stop 0x0000: 0000 0002 Accounting Authentication Attribute (45), length: 6, Value: RADIUS 0x0000: 0000 0001 Username Attribute (1), length: 13, Value: 11947606194 0x0000: 3131 3934 3736 3036 3139 34 NAS ID Attribute (32), length: 16, Value: KAT_8_MERDIVEN 0x0000: 4b41 545f 385f 4d45 5244 4956 454e Called Station Attribute (30), length: 27, Value: DC-9F-DB-34-CF-B4:TDV.NET 0x0000: 4443 2d39 462d 4442 2d33 342d 4346 2d42 0x0010: 343a 5444 562e 4e45 54 NAS Port Type Attribute (61), length: 6, Value: Wireless - IEEE 802.11 0x0000: 0000 0013 NAS Port Attribute (5), length: 6, Value: 0 0x0000: 0000 0000 Calling Station Attribute (31), length: 19, Value: 9C-5C-F9-4B-C2-E1 0x0000: 3943 2d35 432d 4639 2d34 422d 4332 2d45 0x0010: 31 Connect Info Attribute (77), length: 23, Value: CONNECT 0Mbps 802.11b 0x0000: 434f 4e4e 4543 5420 304d 6270 7320 3830 0x0010: 322e 3131 62 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000076 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 36 Accounting Session Time Attribute (46), length: 6, Value: 47 secs 0x0000: 0000 002f Accounting Input Packets Attribute (47), length: 6, Value: 412 0x0000: 0000 019c Accounting Output Packets Attribute (48), length: 6, Value: 263 0x0000: 0000 0107 Accounting Input Octets Attribute (42), length: 6, Value: 97791 0x0000: 0001 7dff Accounting Output Octets Attribute (43), length: 6, Value: 156484 0x0000: 0002 6344 Event Timestamp Attribute (55), length: 6, Value: Tue Feb 7 19:05:09 2017 0x0000: 5899 f035 Accounting Termination Cause Attribute (49), length: 6, Value: User Request 0x0000: 0000 0001 19:05:34.031487 IP (tos 0x0, ttl 64, id 11898, offset 0, flags [none], proto UDP (17), length 257) 192.168.0.38.58259 > 192.168.0.1.1813: [udp sum ok] RADIUS, length: 229 Accounting Request (4), id: 0x3a, Authenticator: fbfa93257081e2964aae2b0f46445dd1 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000075 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 35 Accounting Status Attribute (40), length: 6, Value: Interim-Update 0x0000: 0000 0003 Accounting Authentication Attribute (45), length: 6, Value: RADIUS 0x0000: 0000 0001 Username Attribute (1), length: 13, Value: 99709507736 0x0000: 3939 3730 3935 3037 3733 36 NAS ID Attribute (32), length: 16, Value: KAT_8_MERDIVEN 0x0000: 4b41 545f 385f 4d45 5244 4956 454e Called Station Attribute (30), length: 27, Value: DC-9F-DB-34-CF-B4:TDV.NET 0x0000: 4443 2d39 462d 4442 2d33 342d 4346 2d42 0x0010: 343a 5444 562e 4e45 54 NAS Port Type Attribute (61), length: 6, Value: Wireless - IEEE 802.11 0x0000: 0000 0013 NAS Port Attribute (5), length: 6, Value: 0 0x0000: 0000 0000 Calling Station Attribute (31), length: 19, Value: EC-9B-F3-48-BD-70 0x0000: 4543 2d39 422d 4633 2d34 382d 4244 2d37 0x0010: 30 Connect Info Attribute (77), length: 23, Value: CONNECT 0Mbps 802.11b 0x0000: 434f 4e4e 4543 5420 304d 6270 7320 3830 0x0010: 322e 3131 62 Accounting Session ID Attribute (44), length: 19, Value: 5899CA79-00000075 0x0000: 3538 3939 4341 3739 2d30 3030 3030 3037 0x0010: 35 Class Attribute (25), length: 13, Value: 99709507736 0x0000: 3939 3730 3935 3037 3733 36 Accounting Session Time Attribute (46), length: 6, Value: 10:00 min 0x0000: 0000 0258 Accounting Input Packets Attribute (47), length: 6, Value: 4316 0x0000: 0000 10dc Accounting Output Packets Attribute (48), length: 6, Value: 4462 0x0000: 0000 116e Accounting Input Octets Attribute (42), length: 6, Value: 400013 0x0000: 0006 1a8d Accounting Output Octets Attribute (43), length: 6, Value: 5071812 0x0000: 004d 63c4 Event Timestamp Attribute (55), length: 6, Value: Tue Feb 7 19:05:34 2017 0x0000: 5899 f04e Does anyone have any idea why this is happening? --- This email has been checked for viruses by Avast antivirus software. https://www.avast.com/antivirus

3 8

Option to respond with a different pool in Freeradius DHCP based on interface
by Nataniel Klug 07 Feb '17

07 Feb '17

Hello, I am running a Freeradius DHCP and I want to use the same Freeradius server to serve different pools on different interfaces but I am not seeing a way to make that happen. I was thinking on use the update-control but I was not able to find a way to make it. Scenario is like this: eth1 - 10.192.0.0/10 (pool name "dhcp_ftth") - working just fine eth2 - 10.10.0.024 (pool name "management") Both pools are in a MySQL database. Any ideas on how to make it? Bellow I am posting the result of the "radiusd -Xx" for a request I received: Tue Feb 7 11:35:37 2017 : Debug: radiusd: #### Opening IP addresses and Ports #### Tue Feb 7 11:35:37 2017 : Debug: listen { Tue Feb 7 11:35:37 2017 : Debug: type = "dhcp" Tue Feb 7 11:35:37 2017 : Debug: ipaddr = * Tue Feb 7 11:35:37 2017 : Debug: port = 67 Tue Feb 7 11:35:37 2017 : Debug: } Tue Feb 7 11:35:37 2017 : Debug: Listening on dhcp interface eth1 address * port 67 bound to server dhcp Tue Feb 7 11:35:37 2017 : Info: Ready to process requests Received DHCP-Discover of Id 130b66cc from 0.0.0.0:68 to 255.255.255.255:67 0: 01 01 06 00 13 0b 66 cc 00 00 80 00 00 00 00 00 16: 00 00 00 00 00 00 00 00 00 00 00 00 00 50 56 b8 32: 2c 62 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63 240: 35 01 01 37 06 01 79 03 21 06 2a 0c 08 4d 69 6b 256: 72 6f 54 69 6b 3d 07 01 00 50 56 b8 2c 62 ff 00 272: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 288: 00 00 00 00 00 00 00 00 00 00 00 00 DHCP-Opcode = Client-Message DHCP-Hardware-Type = Ethernet DHCP-Hardware-Address-Length = 6 DHCP-Hop-Count = 0 DHCP-Transaction-Id = 319514316 DHCP-Number-of-Seconds = 0 DHCP-Flags = Broadcast DHCP-Client-IP-Address = 0.0.0.0 DHCP-Your-IP-Address = 0.0.0.0 DHCP-Server-IP-Address = 0.0.0.0 DHCP-Gateway-IP-Address = 0.0.0.0 DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 DHCP-Message-Type = DHCP-Discover DHCP-Parameter-Request-List = DHCP-Subnet-Mask DHCP-Parameter-Request-List = DHCP-Classless-Static-Route DHCP-Parameter-Request-List = DHCP-Router-Address DHCP-Parameter-Request-List = DHCP-Static-Routes DHCP-Parameter-Request-List = DHCP-Domain-Name-Server DHCP-Parameter-Request-List = DHCP-NTP-Servers DHCP-Hostname = "MikroTik" DHCP-Client-Identifier = 0x01005056b82c62 Tue Feb 7 11:35:41 2017 : Debug: (0) Received code 1025 Id 319514316 from 0.0.0.0:68 to 255.255.255.255:67 length 300 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Opcode = Client-Message Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Hardware-Type = Ethernet Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Hardware-Address-Length = 6 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Hop-Count = 0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Transaction-Id = 319514316 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Number-of-Seconds = 0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Flags = Broadcast Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Client-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Your-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Server-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Gateway-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Message-Type = DHCP-Discover Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Parameter-Request-List = DHCP-Subnet-Mask Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Parameter-Request-List = DHCP-Classless-Static-Route Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Parameter-Request-List = DHCP-Router-Address Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Parameter-Request-List = DHCP-Static-Routes Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Parameter-Request-List = DHCP-Domain-Name-Server Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Parameter-Request-List = DHCP-NTP-Servers Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Hostname = "MikroTik" Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Client-Identifier = 0x01005056b82c62 Tue Feb 7 11:35:41 2017 : Debug: Trying sub-section dhcp DHCP-Discover {...} Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp DHCP-Discover { Tue Feb 7 11:35:41 2017 : Debug: (0) update reply { Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Message-Type = DHCP-Offer Tue Feb 7 11:35:41 2017 : Debug: (0) } # update reply = noop Tue Feb 7 11:35:41 2017 : Debug: (0) update reply { Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-Domain-Name-Server = 186.251.148.22 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-Domain-Name-Server += 186.251.148.21 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-Subnet-Mask = 255.192.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-Router-Address = 10.192.0.1 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-IP-Address-Lease-Time = 86400 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-DHCP-Server-Identifier = 10.192.0.5 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-NTP-Servers = 186.251.148.3 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-NTP-Servers += 186.251.148.2 Tue Feb 7 11:35:41 2017 : Debug: (0) } # update reply = noop Tue Feb 7 11:35:41 2017 : Debug: (0) update control { Tue Feb 7 11:35:41 2017 : Debug: (0) &Pool-Name := "dhcp_ftth" Tue Feb 7 11:35:41 2017 : Debug: (0) } # update control = noop Tue Feb 7 11:35:41 2017 : Debug: (0) policy dhcp_sqlippool.post-auth { Tue Feb 7 11:35:41 2017 : Debug: (0) update request { Tue Feb 7 11:35:41 2017 : Debug: (0) EXPAND DHCP-%{DHCP-Client-Hardware-Address} Tue Feb 7 11:35:41 2017 : Debug: (0) --> DHCP-00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (0) &User-Name = DHCP-00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (0) EXPAND %{DHCP-Client-Hardware-Address} Tue Feb 7 11:35:41 2017 : Debug: (0) --> 00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (0) &Calling-Station-Id = 00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (0) EXPAND %{%{DHCP-Gateway-IP-Address}:-127.0.0.1} Tue Feb 7 11:35:41 2017 : Debug: (0) --> 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) &NAS-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) &Acct-Status-Type = Start Tue Feb 7 11:35:41 2017 : Debug: (0) } # update request = noop Tue Feb 7 11:35:41 2017 : Debug: (0) modsingle[post-auth]: calling dhcp_sqlippool (rlm_sqlippool) Tue Feb 7 11:35:41 2017 : Debug: rlm_sql (sql): Reserved connection (0) Tue Feb 7 11:35:41 2017 : Debug: START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: EXPAND START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: --> START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Executing query: START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: UPDATE dhcp SET nasipaddress = '', pool_key = 0, callingstationid = '', username = '', expiry_time = NOW() WHERE expiry_time <= NOW() - INTERVAL 1 SECOND AND nasipaddress = '%{Nas-IP-Address}' Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> UPDATE dhcp SET nasipaddress = '', pool_key = 0, callingstationid = '', username = '', expiry_time = NOW() WHERE expiry_time <= NOW() - INTERVAL 1 SECOND AND nasipaddress = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> NAS-IP-Address Tue Feb 7 11:35:41 2017 : Debug: literal --> ' Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: EXPAND UPDATE dhcp SET nasipaddress = '', pool_key = 0, callingstationid = '', username = '', expiry_time = NOW() WHERE expiry_time <= NOW() - INTERVAL 1 SECOND AND nasipaddress = '%{Nas-IP-Address}' Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: --> UPDATE dhcp SET nasipaddress = '', pool_key = 0, callingstationid = '', username = '', expiry_time = NOW() WHERE expiry_time <= NOW() - INTERVAL 1 SECOND AND nasipaddress = '0.0.0.0' Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Executing query: UPDATE dhcp SET nasipaddress = '', pool_key = 0, callingstationid = '', username = '', expiry_time = NOW() WHERE expiry_time <= NOW() - INTERVAL 1 SECOND AND nasipaddress = '0.0.0.0' Tue Feb 7 11:35:41 2017 : Debug: rlm_sql_mysql: Rows matched: 0 Changed: 0 Warnings: 0 Tue Feb 7 11:35:41 2017 : Debug: COMMIT Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> COMMIT Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: EXPAND COMMIT Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: --> COMMIT Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Executing query: COMMIT Tue Feb 7 11:35:41 2017 : Debug: START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: EXPAND START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: --> START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Executing query: START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: SELECT framedipaddress FROM dhcp WHERE pool_name = '%{control:Pool-Name}' AND (callingstationid = '%{Calling-Station-Id}' or callingstationid = '') ORDER BY (callingstationid <> '%{Calling-Station-Id}'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> SELECT framedipaddress FROM dhcp WHERE pool_name = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Pool-Name Tue Feb 7 11:35:41 2017 : Debug: literal --> ' AND (callingstationid = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> ' or callingstationid = '') ORDER BY (callingstationid <> ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> '), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: EXPAND SELECT framedipaddress FROM dhcp WHERE pool_name = '%{control:Pool-Name}' AND (callingstationid = '%{Calling-Station-Id}' or callingstationid = '') ORDER BY (callingstationid <> '%{Calling-Station-Id}'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: --> SELECT framedipaddress FROM dhcp WHERE pool_name = 'dhcp_ftth' AND (callingstationid = '00:50:56:b8:2c:62' or callingstationid = '') ORDER BY (callingstationid <> '00:50:56:b8:2c:62'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Executing select query: SELECT framedipaddress FROM dhcp WHERE pool_name = 'dhcp_ftth' AND (callingstationid = '00:50:56:b8:2c:62' or callingstationid = '') ORDER BY (callingstationid <> '00:50:56:b8:2c:62'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Allocated IP 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: UPDATE dhcp SET nasipaddress = '%{NAS-IP-Address}', pool_key = '%{Calling-Station-Id}', callingstationid = '%{Calling-Station-Id}', username = '%{User-Name}', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> UPDATE dhcp SET nasipaddress = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> NAS-IP-Address Tue Feb 7 11:35:41 2017 : Debug: literal --> ', pool_key = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> ', callingstationid = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> ', username = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> User-Name Tue Feb 7 11:35:41 2017 : Debug: literal --> ', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: EXPAND UPDATE dhcp SET nasipaddress = '%{NAS-IP-Address}', pool_key = '%{Calling-Station-Id}', callingstationid = '%{Calling-Station-Id}', username = '%{User-Name}', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: --> UPDATE dhcp SET nasipaddress = '0.0.0.0', pool_key = '00:50:56:b8:2c:62', callingstationid = '00:50:56:b8:2c:62', username = 'DHCP-00:50:56:b8:2c:62', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Executing query: UPDATE dhcp SET nasipaddress = '0.0.0.0', pool_key = '00:50:56:b8:2c:62', callingstationid = '00:50:56:b8:2c:62', username = 'DHCP-00:50:56:b8:2c:62', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: rlm_sql_mysql: Rows matched: 1 Changed: 1 Warnings: 0 Tue Feb 7 11:35:41 2017 : Debug: COMMIT Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> COMMIT Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: EXPAND COMMIT Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: --> COMMIT Tue Feb 7 11:35:41 2017 : Debug: (0) dhcp_sqlippool: Executing query: COMMIT Tue Feb 7 11:35:41 2017 : Debug: rlm_sql (sql): Released connection (0) Tue Feb 7 11:35:41 2017 : Info: rlm_sql (sql): Need 5 more connections to reach 10 spares Tue Feb 7 11:35:41 2017 : Info: rlm_sql (sql): Opening additional connection (5), 1 of 27 pending slots used Tue Feb 7 11:35:41 2017 : Debug: rlm_sql_mysql: Starting connect to MySQL server Tue Feb 7 11:35:41 2017 : Debug: rlm_sql_mysql: Connected to database 'acs' on 172.31.255.63 via TCP/IP, server version 5.5.5-10.0.0 2.0.3-maxscale, protocol version 10 Tue Feb 7 11:35:41 2017 : Debug: (0) modsingle[post-auth]: returned from dhcp_sqlippool (rlm_sqlippool) Tue Feb 7 11:35:41 2017 : Debug: (0) [dhcp_sqlippool] = ok Tue Feb 7 11:35:41 2017 : Debug: (0) if (ok) { Tue Feb 7 11:35:41 2017 : Debug: (0) if (ok) -> TRUE Tue Feb 7 11:35:41 2017 : Debug: (0) if (ok) { Tue Feb 7 11:35:41 2017 : Debug: (0) update reply { Tue Feb 7 11:35:41 2017 : Debug: (0) EXPAND %{reply:Framed-IP-Address} Tue Feb 7 11:35:41 2017 : Debug: (0) --> 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (0) &DHCP-Your-IP-Address = 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (0) } # update reply = noop Tue Feb 7 11:35:41 2017 : Debug: (0) } # if (ok) = noop Tue Feb 7 11:35:41 2017 : Debug: (0) } # policy dhcp_sqlippool.post-auth = ok Tue Feb 7 11:35:41 2017 : Debug: (0) modsingle[post-auth]: calling ok (rlm_always) Tue Feb 7 11:35:41 2017 : Debug: (0) modsingle[post-auth]: returned from ok (rlm_always) Tue Feb 7 11:35:41 2017 : Debug: (0) [ok] = ok Tue Feb 7 11:35:41 2017 : Debug: (0) } # dhcp DHCP-Discover = ok Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP: Reply will be broadcast Tue Feb 7 11:35:41 2017 : Debug: (0) Sent code 1026 Id 319514316 from 10.192.0.5:67 to 255.255.255.255:68 length 0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Message-Type = DHCP-Offer Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Domain-Name-Server = 186.251.148.22 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Domain-Name-Server += 186.251.148.21 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Subnet-Mask = 255.192.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Router-Address = 10.192.0.1 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-IP-Address-Lease-Time = 86400 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-DHCP-Server-Identifier = 10.192.0.5 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-NTP-Servers = 186.251.148.3 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-NTP-Servers += 186.251.148.2 Tue Feb 7 11:35:41 2017 : Debug: (0) Framed-IP-Address = 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Your-IP-Address = 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Opcode = Server-Message Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Hardware-Type = Ethernet Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Hardware-Address-Length = 6 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Hop-Count = 0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Transaction-Id = 319514316 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Flags = Broadcast Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Client-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Gateway-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (0) DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 DHCP-Opcode = Server-Message DHCP-Hardware-Type = Ethernet DHCP-Hardware-Address-Length = 6 DHCP-Hop-Count = 0 DHCP-Transaction-Id = 319514316 DHCP-Number-of-Seconds = 0 DHCP-Flags = Broadcast DHCP-Client-IP-Address = 0.0.0.0 DHCP-Your-IP-Address = 10.192.1.1 DHCP-Server-IP-Address = 0.0.0.0 DHCP-Gateway-IP-Address = 0.0.0.0 DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 DHCP-Server-Host-Name = "" DHCP-Boot-Filename = "" DHCP-Subnet-Mask = 255.192.0.0 DHCP-Router-Address = 10.192.0.1 DHCP-Domain-Name-Server = 186.251.148.22 DHCP-Domain-Name-Server += 186.251.148.21 DHCP-NTP-Servers = 186.251.148.3 DHCP-NTP-Servers += 186.251.148.2 DHCP-IP-Address-Lease-Time = 86400 DHCP-DHCP-Server-Identifier = 10.192.0.5 DHCP Sending 300 bytes 0: 02 01 06 00 13 0b 66 cc 00 00 80 00 00 00 00 00 16: 0a c0 01 01 00 00 00 00 00 00 00 00 00 50 56 b8 32: 2c 62 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63 240: 35 01 02 01 04 ff c0 00 00 03 04 0a c0 00 01 06 256: 08 ba fb 94 16 ba fb 94 15 2a 08 ba fb 94 03 ba 272: fb 94 02 33 04 00 01 51 80 36 04 0a c0 00 05 ff 288: 00 00 00 00 00 00 00 00 00 00 00 00 Sending DHCP-Offer Id 130b66cc from 10.192.0.5:67 to 255.255.255.255:68 Tue Feb 7 11:35:41 2017 : Debug: (0) Finished request Tue Feb 7 11:35:41 2017 : Debug: (0) Cleaning up request packet ID 319514316 with timestamp +4 Tue Feb 7 11:35:41 2017 : Info: Ready to process requests Received DHCP-Request of Id 130b66cc from 0.0.0.0:68 to 255.255.255.255:67 0: 01 01 06 00 13 0b 66 cc 00 00 80 00 00 00 00 00 16: 00 00 00 00 00 00 00 00 00 00 00 00 00 50 56 b8 32: 2c 62 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63 240: 35 01 03 36 04 0a c0 00 05 32 04 0a c0 01 01 37 256: 06 01 79 03 21 06 2a 0c 08 4d 69 6b 72 6f 54 69 272: 6b 3d 07 01 00 50 56 b8 2c 62 ff 00 00 00 00 00 288: 00 00 00 00 00 00 00 00 00 00 00 00 DHCP-Opcode = Client-Message DHCP-Hardware-Type = Ethernet DHCP-Hardware-Address-Length = 6 DHCP-Hop-Count = 0 DHCP-Transaction-Id = 319514316 DHCP-Number-of-Seconds = 0 DHCP-Flags = Broadcast DHCP-Client-IP-Address = 0.0.0.0 DHCP-Your-IP-Address = 0.0.0.0 DHCP-Server-IP-Address = 0.0.0.0 DHCP-Gateway-IP-Address = 0.0.0.0 DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 DHCP-Message-Type = DHCP-Request DHCP-DHCP-Server-Identifier = 10.192.0.5 DHCP-Requested-IP-Address = 10.192.1.1 DHCP-Parameter-Request-List = DHCP-Subnet-Mask DHCP-Parameter-Request-List = DHCP-Classless-Static-Route DHCP-Parameter-Request-List = DHCP-Router-Address DHCP-Parameter-Request-List = DHCP-Static-Routes DHCP-Parameter-Request-List = DHCP-Domain-Name-Server DHCP-Parameter-Request-List = DHCP-NTP-Servers DHCP-Hostname = "MikroTik" DHCP-Client-Identifier = 0x01005056b82c62 Tue Feb 7 11:35:41 2017 : Debug: (1) Received code 1027 Id 319514316 from 0.0.0.0:68 to 255.255.255.255:67 length 300 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Opcode = Client-Message Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Hardware-Type = Ethernet Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Hardware-Address-Length = 6 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Hop-Count = 0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Transaction-Id = 319514316 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Number-of-Seconds = 0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Flags = Broadcast Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Client-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Your-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Server-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Gateway-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Message-Type = DHCP-Request Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-DHCP-Server-Identifier = 10.192.0.5 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Requested-IP-Address = 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Parameter-Request-List = DHCP-Subnet-Mask Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Parameter-Request-List = DHCP-Classless-Static-Route Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Parameter-Request-List = DHCP-Router-Address Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Parameter-Request-List = DHCP-Static-Routes Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Parameter-Request-List = DHCP-Domain-Name-Server Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Parameter-Request-List = DHCP-NTP-Servers Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Hostname = "MikroTik" Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Client-Identifier = 0x01005056b82c62 Tue Feb 7 11:35:41 2017 : Debug: Trying sub-section dhcp DHCP-Request {...} Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp DHCP-Request { Tue Feb 7 11:35:41 2017 : Debug: (1) update reply { Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-Message-Type = DHCP-Ack Tue Feb 7 11:35:41 2017 : Debug: (1) } # update reply = noop Tue Feb 7 11:35:41 2017 : Debug: (1) update reply { Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-Domain-Name-Server = 186.251.148.22 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-Domain-Name-Server += 186.251.148.21 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-Subnet-Mask = 255.192.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-Router-Address = 10.192.0.1 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-IP-Address-Lease-Time = 86400 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-DHCP-Server-Identifier = 10.192.0.5 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-NTP-Servers = 186.251.148.3 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-NTP-Servers += 186.251.148.2 Tue Feb 7 11:35:41 2017 : Debug: (1) } # update reply = noop Tue Feb 7 11:35:41 2017 : Debug: (1) update control { Tue Feb 7 11:35:41 2017 : Debug: (1) &Pool-Name := "dhcp_ftth" Tue Feb 7 11:35:41 2017 : Debug: (1) } # update control = noop Tue Feb 7 11:35:41 2017 : Debug: (1) policy dhcp_sqlippool.post-auth { Tue Feb 7 11:35:41 2017 : Debug: (1) update request { Tue Feb 7 11:35:41 2017 : Debug: (1) EXPAND DHCP-%{DHCP-Client-Hardware-Address} Tue Feb 7 11:35:41 2017 : Debug: (1) --> DHCP-00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (1) &User-Name = DHCP-00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (1) EXPAND %{DHCP-Client-Hardware-Address} Tue Feb 7 11:35:41 2017 : Debug: (1) --> 00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (1) &Calling-Station-Id = 00:50:56:b8:2c:62 Tue Feb 7 11:35:41 2017 : Debug: (1) EXPAND %{%{DHCP-Gateway-IP-Address}:-127.0.0.1} Tue Feb 7 11:35:41 2017 : Debug: (1) --> 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) &NAS-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) &Acct-Status-Type = Start Tue Feb 7 11:35:41 2017 : Debug: (1) } # update request = noop Tue Feb 7 11:35:41 2017 : Debug: (1) modsingle[post-auth]: calling dhcp_sqlippool (rlm_sqlippool) Tue Feb 7 11:35:41 2017 : Debug: rlm_sql (sql): Reserved connection (1) Tue Feb 7 11:35:41 2017 : Debug: START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: EXPAND START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: --> START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: Executing query: START TRANSACTION Tue Feb 7 11:35:41 2017 : Debug: SELECT framedipaddress FROM dhcp WHERE pool_name = '%{control:Pool-Name}' AND (callingstationid = '%{Calling-Station-Id}' or callingstationid = '') ORDER BY (callingstationid <> '%{Calling-Station-Id}'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> SELECT framedipaddress FROM dhcp WHERE pool_name = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Pool-Name Tue Feb 7 11:35:41 2017 : Debug: literal --> ' AND (callingstationid = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> ' or callingstationid = '') ORDER BY (callingstationid <> ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> '), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: EXPAND SELECT framedipaddress FROM dhcp WHERE pool_name = '%{control:Pool-Name}' AND (callingstationid = '%{Calling-Station-Id}' or callingstationid = '') ORDER BY (callingstationid <> '%{Calling-Station-Id}'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: --> SELECT framedipaddress FROM dhcp WHERE pool_name = 'dhcp_ftth' AND (callingstationid = '00:50:56:b8:2c:62' or callingstationid = '') ORDER BY (callingstationid <> '00:50:56:b8:2c:62'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: Executing select query: SELECT framedipaddress FROM dhcp WHERE pool_name = 'dhcp_ftth' AND (callingstationid = '00:50:56:b8:2c:62' or callingstationid = '') ORDER BY (callingstationid <> '00:50:56:b8:2c:62'), expiry_time LIMIT 1 FOR UPDATE Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: Allocated IP 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: UPDATE dhcp SET nasipaddress = '%{NAS-IP-Address}', pool_key = '%{Calling-Station-Id}', callingstationid = '%{Calling-Station-Id}', username = '%{User-Name}', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> UPDATE dhcp SET nasipaddress = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> NAS-IP-Address Tue Feb 7 11:35:41 2017 : Debug: literal --> ', pool_key = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> ', callingstationid = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> Calling-Station-Id Tue Feb 7 11:35:41 2017 : Debug: literal --> ', username = ' Tue Feb 7 11:35:41 2017 : Debug: attribute --> User-Name Tue Feb 7 11:35:41 2017 : Debug: literal --> ', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: EXPAND UPDATE dhcp SET nasipaddress = '%{NAS-IP-Address}', pool_key = '%{Calling-Station-Id}', callingstationid = '%{Calling-Station-Id}', username = '%{User-Name}', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: --> UPDATE dhcp SET nasipaddress = '0.0.0.0', pool_key = '00:50:56:b8:2c:62', callingstationid = '00:50:56:b8:2c:62', username = 'DHCP-00:50:56:b8:2c:62', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: Executing query: UPDATE dhcp SET nasipaddress = '0.0.0.0', pool_key = '00:50:56:b8:2c:62', callingstationid = '00:50:56:b8:2c:62', username = 'DHCP-00:50:56:b8:2c:62', expiry_time = NOW() + INTERVAL 86400 SECOND WHERE framedipaddress = '10.192.1.1' Tue Feb 7 11:35:41 2017 : Debug: rlm_sql_mysql: Rows matched: 1 Changed: 1 Warnings: 0 Tue Feb 7 11:35:41 2017 : Debug: COMMIT Tue Feb 7 11:35:41 2017 : Debug: Parsed xlat tree: Tue Feb 7 11:35:41 2017 : Debug: literal --> COMMIT Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: EXPAND COMMIT Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: --> COMMIT Tue Feb 7 11:35:41 2017 : Debug: (1) dhcp_sqlippool: Executing query: COMMIT Tue Feb 7 11:35:41 2017 : Debug: rlm_sql (sql): Released connection (1) Tue Feb 7 11:35:41 2017 : Debug: (1) modsingle[post-auth]: returned from dhcp_sqlippool (rlm_sqlippool) Tue Feb 7 11:35:41 2017 : Debug: (1) [dhcp_sqlippool] = ok Tue Feb 7 11:35:41 2017 : Debug: (1) if (ok) { Tue Feb 7 11:35:41 2017 : Debug: (1) if (ok) -> TRUE Tue Feb 7 11:35:41 2017 : Debug: (1) if (ok) { Tue Feb 7 11:35:41 2017 : Debug: (1) update reply { Tue Feb 7 11:35:41 2017 : Debug: (1) EXPAND %{reply:Framed-IP-Address} Tue Feb 7 11:35:41 2017 : Debug: (1) --> 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (1) &DHCP-Your-IP-Address = 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (1) } # update reply = noop Tue Feb 7 11:35:41 2017 : Debug: (1) } # if (ok) = noop Tue Feb 7 11:35:41 2017 : Debug: (1) } # policy dhcp_sqlippool.post-auth = ok Tue Feb 7 11:35:41 2017 : Debug: (1) modsingle[post-auth]: calling ok (rlm_always) Tue Feb 7 11:35:41 2017 : Debug: (1) modsingle[post-auth]: returned from ok (rlm_always) Tue Feb 7 11:35:41 2017 : Debug: (1) [ok] = ok Tue Feb 7 11:35:41 2017 : Debug: (1) } # dhcp DHCP-Request = ok Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP: Reply will be broadcast Tue Feb 7 11:35:41 2017 : Debug: (1) Sent code 1029 Id 319514316 from 10.192.0.5:67 to 255.255.255.255:68 length 0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Message-Type = DHCP-Ack Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Domain-Name-Server = 186.251.148.22 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Domain-Name-Server += 186.251.148.21 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Subnet-Mask = 255.192.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Router-Address = 10.192.0.1 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-IP-Address-Lease-Time = 86400 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-DHCP-Server-Identifier = 10.192.0.5 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-NTP-Servers = 186.251.148.3 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-NTP-Servers += 186.251.148.2 Tue Feb 7 11:35:41 2017 : Debug: (1) Framed-IP-Address = 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Your-IP-Address = 10.192.1.1 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Opcode = Server-Message Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Hardware-Type = Ethernet Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Hardware-Address-Length = 6 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Hop-Count = 0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Transaction-Id = 319514316 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Flags = Broadcast Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Client-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Gateway-IP-Address = 0.0.0.0 Tue Feb 7 11:35:41 2017 : Debug: (1) DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 DHCP-Opcode = Server-Message DHCP-Hardware-Type = Ethernet DHCP-Hardware-Address-Length = 6 DHCP-Hop-Count = 0 DHCP-Transaction-Id = 319514316 DHCP-Number-of-Seconds = 0 DHCP-Flags = Broadcast DHCP-Client-IP-Address = 0.0.0.0 DHCP-Your-IP-Address = 10.192.1.1 DHCP-Server-IP-Address = 0.0.0.0 DHCP-Gateway-IP-Address = 0.0.0.0 DHCP-Client-Hardware-Address = 00:50:56:b8:2c:62 DHCP-Server-Host-Name = "" DHCP-Boot-Filename = "" DHCP-Subnet-Mask = 255.192.0.0 DHCP-Router-Address = 10.192.0.1 DHCP-Domain-Name-Server = 186.251.148.22 DHCP-Domain-Name-Server += 186.251.148.21 DHCP-NTP-Servers = 186.251.148.3 DHCP-NTP-Servers += 186.251.148.2 DHCP-IP-Address-Lease-Time = 86400 DHCP-DHCP-Server-Identifier = 10.192.0.5 DHCP Sending 300 bytes 0: 02 01 06 00 13 0b 66 cc 00 00 80 00 00 00 00 00 16: 0a c0 01 01 00 00 00 00 00 00 00 00 00 50 56 b8 32: 2c 62 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63 240: 35 01 05 01 04 ff c0 00 00 03 04 0a c0 00 01 06 256: 08 ba fb 94 16 ba fb 94 15 2a 08 ba fb 94 03 ba 272: fb 94 02 33 04 00 01 51 80 36 04 0a c0 00 05 ff 288: 00 00 00 00 00 00 00 00 00 00 00 00 Sending DHCP-Ack Id 130b66cc from 10.192.0.5:67 to 255.255.255.255:68 Tue Feb 7 11:35:41 2017 : Debug: (1) Finished request Tue Feb 7 11:35:41 2017 : Debug: (1) Cleaning up request packet ID 319514316 with timestamp +4 Tue Feb 7 11:35:41 2017 : Info: Ready to process requests Atenciosamente, *Nataniel Klug* | nataniel.klug(a)gmail.com

2 4

Problem making certs
by Joe Price 07 Feb '17

07 Feb '17

Hello: I'm new to Freeradius and to Linux (Ubuntu Server 16.04) as well. I've got my first server up and running Freeradius v3 and can successfully authorize users on my network with a username and password. Works great and was easy to do. My AXis ipCCTV cameras require EAP-TLS and this is where I'm stuck. My clients require a CA Certificate, a Client cert and private key, an EAP identity and Private key password for the EAP identity. I'm not exactly sure where all this comes from. I can't get "make" or "make client" to do anything other than give me a "No targets specified and no makefile found" error. How do I complete the jump to EAP-TLS? Any guides online? Thanks, Joe

4 3