Re: Hex to Cleartext

classic Classic list List threaded Threaded
5 messages Options
| Threaded
Open this post in threaded view
|

Re: Hex to Cleartext

Chinnapaiyan, Nagamani
This “0x%{redis:…}” doesnot work for me.

Lines in config file:
        if(updated && "0x%{redis: GET %{control:Pool-Name}:option}") {
                update reply {
                        &DHCP-Vendor = "0x%{redis: GET %{control:Pool-Name}:option}"
                }
        }
It parses the if condition correctly.. but  the assignment part somehow it doesnot process it correctly.

Debug output:
Wed Dec  5 04:44:41 2018 : Info  : FreeRADIUS Version 4.0.0
Wed Dec  5 04:44:41 2018 : Info  : Copyright 1999-2018 The FreeRADIUS server project and contributors
Wed Dec  5 04:44:41 2018 : Info  : There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
Wed Dec  5 04:44:41 2018 : Info  : PARTICULAR PURPOSE
Wed Dec  5 04:44:41 2018 : Info  : You may redistribute copies of FreeRADIUS under the terms of the
Wed Dec  5 04:44:41 2018 : Info  : GNU General Public License
Wed Dec  5 04:44:41 2018 : Info  : For more information about these matters, see the file named COPYRIGHT
Wed Dec  5 04:44:41 2018 : Info  : Starting - reading configuration files ...
Wed Dec  5 04:44:41 2018 : Debug : Including dictionary file "/usr/local/share/freeradius/dictionary"
Wed Dec  5 04:44:41 2018 : Debug : Including dictionary file "/usr/local/etc/raddb/dictionary"
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/radiusd.conf
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/clients.conf
Wed Dec  5 04:44:41 2018 : Debug : Including files in directory "/usr/local/etc/raddb/mods-enabled/"
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/always
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/attr_filter
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/cache_eap
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/chap
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/client
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/detail
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/detail.log
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/digest
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/dhcpv4
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/eap_inner
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/echo
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/escape
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/exec
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/expiration
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/expr
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/files
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/linelog
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/logintime
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/mschap
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/ntlm_auth
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/pam
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/pap
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/passwd
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/radius
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/radutmp
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/soh
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/sradutmp
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/stats
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/unix
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/unpack
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/utf8
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/mac2ip
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/redis_ippool
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/mods-enabled/redis
Wed Dec  5 04:44:41 2018 : Debug : Including files in directory "/usr/local/etc/raddb/policy.d/"
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/abfab-tr
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/accounting
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/canonicalization
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/control
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/cui
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/debug
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/dhcp
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/eap
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/filter
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/operator-name
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/time
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/policy.d/vendor
Wed Dec  5 04:44:41 2018 : Debug : Including files in directory "/usr/local/etc/raddb/sites-enabled/"
Wed Dec  5 04:44:41 2018 : Debug : including configuration file /usr/local/etc/raddb/sites-enabled/dhcp
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary proto_dhcpv4
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "proto_dhcpv4"
Wed Dec  5 04:44:41 2018 : Debug : Parsing security rules to bootstrap UID / GID / chroot / etc.
Wed Dec  5 04:44:41 2018 : Debug : main {
Wed Dec  5 04:44:41 2018 : Debug :   security {
Wed Dec  5 04:44:41 2018 : Debug :     allow_core_dumps = no
Wed Dec  5 04:44:41 2018 : Debug :     allow_vulnerable_openssl = "no"
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :   name = radiusd
Wed Dec  5 04:44:41 2018 : Debug :   name = "radiusd"
Wed Dec  5 04:44:41 2018 : Debug :   prefix = "/usr/local"
Wed Dec  5 04:44:41 2018 : Debug :   local_state_dir = "/usr/local/var"
Wed Dec  5 04:44:41 2018 : Debug :   run_dir = "/usr/local/var/run/radiusd"
Wed Dec  5 04:44:41 2018 : Debug : }
Wed Dec  5 04:44:41 2018 : Debug : Parsing main configuration.
Wed Dec  5 04:44:41 2018 : Debug : main {
Wed Dec  5 04:44:41 2018 : Debug :   server dhcp {
Wed Dec  5 04:44:41 2018 : Debug :     namespace = "dhcpv4"
Wed Dec  5 04:44:41 2018 : Debug :     listen {
Wed Dec  5 04:44:41 2018 : Debug :       type = DHCP-Discover
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "proto_dhcpv4_base"
Wed Dec  5 04:44:41 2018 : Debug :       type = DHCP-Request
Wed Dec  5 04:44:41 2018 : Debug :       type = DHCP-Inform
Wed Dec  5 04:44:41 2018 : Debug :       type = DHCP-Release
Wed Dec  5 04:44:41 2018 : Debug :       type = DHCP-Decline
Wed Dec  5 04:44:41 2018 : Debug :       transport = udp
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary proto_dhcpv4_udp
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "proto_dhcpv4_udp"
Wed Dec  5 04:44:41 2018 : Debug :       udp {
Wed Dec  5 04:44:41 2018 : Debug :         ipaddr = 10.43.18.92
Wed Dec  5 04:44:41 2018 : Debug :         port = 67
Wed Dec  5 04:44:41 2018 : Debug :         broadcast = no
Wed Dec  5 04:44:41 2018 : Debug :         networks {
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Debug :         max_packet_size = 4096
Wed Dec  5 04:44:41 2018 : Debug :         max_attributes = 0
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       limit {
Wed Dec  5 04:44:41 2018 : Debug :         idle_timeout = 30.000000
Wed Dec  5 04:44:41 2018 : Debug :         nak_lifetime = 30.000000
Wed Dec  5 04:44:41 2018 : Debug :         max_connections = 1024
Wed Dec  5 04:44:41 2018 : Debug :         max_clients = 256
Wed Dec  5 04:44:41 2018 : Debug :         max_pending_packets = 256
Wed Dec  5 04:44:41 2018 : Debug :         priority {
Wed Dec  5 04:44:41 2018 : Debug :           DHCP-Discover = normal
Wed Dec  5 04:44:41 2018 : Debug :           DHCP-Request = normal
Wed Dec  5 04:44:41 2018 : Debug :           DHCP-Decline = normal
Wed Dec  5 04:44:41 2018 : Debug :           DHCP-Release = normal
Wed Dec  5 04:44:41 2018 : Debug :           DHCP-Inform = normal
Wed Dec  5 04:44:41 2018 : Debug :           DHCP-Lease-Query = low
Wed Dec  5 04:44:41 2018 : Debug :           DHCP-Bulk-Lease-Query = low
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :   security {
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :   sbin_dir = "/usr/local/sbin"
Wed Dec  5 04:44:41 2018 : Debug :   logdir = "/usr/local/var/log/radius"
Wed Dec  5 04:44:41 2018 : Debug :   libdir = "/usr/local/lib"
Wed Dec  5 04:44:41 2018 : Debug :   radacctdir = "/usr/local/var/log/radius/radacct"
Wed Dec  5 04:44:41 2018 : Debug :   reverse_lookups = no
Wed Dec  5 04:44:41 2018 : Debug :   reverse_lookups = no
Wed Dec  5 04:44:41 2018 : Debug :   hostname_lookups = yes
Wed Dec  5 04:44:41 2018 : Debug :   hostname_lookups = yes
Wed Dec  5 04:44:41 2018 : Debug :   max_request_time = 30
Wed Dec  5 04:44:41 2018 : Debug :   max_request_time = 30
Wed Dec  5 04:44:41 2018 : Debug :   pidfile = "/usr/local/var/run/radiusd/radiusd.pid"
Wed Dec  5 04:44:41 2018 : Debug :   debug_level = 0
Wed Dec  5 04:44:41 2018 : Debug :   log {
Wed Dec  5 04:44:41 2018 : Debug :     colourise = yes
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :   resources {
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :   thread pool {
Wed Dec  5 04:44:41 2018 : Debug :     num_networks = 1
Wed Dec  5 04:44:41 2018 : Debug :     num_networks = 1
Wed Dec  5 04:44:41 2018 : Debug :     num_workers = 4
Wed Dec  5 04:44:41 2018 : Debug :     num_workers = 4
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug : }
Wed Dec  5 04:44:41 2018 : Info  : Switching to configured log settings
Wed Dec  5 04:44:41 2018 : Debug : radiusd: #### Loading Clients ####
Wed Dec  5 04:44:41 2018 : Debug :   client localhost {
Wed Dec  5 04:44:41 2018 : Debug :     ipaddr = 127.0.0.1
Wed Dec  5 04:44:41 2018 : Debug :     require_message_authenticator = no
Wed Dec  5 04:44:41 2018 : Debug :     secret = <<< secret >>>
Wed Dec  5 04:44:41 2018 : Debug :     proto = "*"
Wed Dec  5 04:44:41 2018 : Debug :     limit {
Wed Dec  5 04:44:41 2018 : Debug :       max_connections = 16
Wed Dec  5 04:44:41 2018 : Debug :       lifetime = 0
Wed Dec  5 04:44:41 2018 : Debug :       idle_timeout = 30
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :   client localhost_ipv6 {
Wed Dec  5 04:44:41 2018 : Debug :     ipv6addr = ::1
Wed Dec  5 04:44:41 2018 : Debug :     require_message_authenticator = no
Wed Dec  5 04:44:41 2018 : Debug :     secret = <<< secret >>>
Wed Dec  5 04:44:41 2018 : Debug :     limit {
Wed Dec  5 04:44:41 2018 : Debug :       max_connections = 16
Wed Dec  5 04:44:41 2018 : Debug :       lifetime = 0
Wed Dec  5 04:44:41 2018 : Debug :       idle_timeout = 30
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :   client private-network-1 {
Wed Dec  5 04:44:41 2018 : Debug :     ipaddr = 10.43.18.0/24
Wed Dec  5 04:44:41 2018 : Debug :     require_message_authenticator = no
Wed Dec  5 04:44:41 2018 : Debug :     secret = <<< secret >>>
Wed Dec  5 04:44:41 2018 : Debug :     limit {
Wed Dec  5 04:44:41 2018 : Debug :       max_connections = 16
Wed Dec  5 04:44:41 2018 : Debug :       lifetime = 0
Wed Dec  5 04:44:41 2018 : Debug :       idle_timeout = 30
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Info  : Debugger not attached
Wed Dec  5 04:44:41 2018 : Debug : #### Bootstrapping listeners ####
Wed Dec  5 04:44:41 2018 : Debug : #### Bootstrapping modules ####
Wed Dec  5 04:44:41 2018 : Debug :  modules {
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_always"
Wed Dec  5 04:44:41 2018 : Debug :     always reject {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "reject"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always fail {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "fail"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always ok {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "ok"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always handled {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "handled"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always invalid {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "invalid"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always userlock {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "userlock"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always notfound {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "notfound"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always noop {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "noop"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     always updated {
Wed Dec  5 04:44:41 2018 : Debug :       rcode = "updated"
Wed Dec  5 04:44:41 2018 : Debug :       simulcount = 0
Wed Dec  5 04:44:41 2018 : Debug :       mpp = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_attr_filter
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_attr_filter"
Wed Dec  5 04:44:41 2018 : Debug :     attr_filter attr_filter.pre-proxy {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/etc/raddb/mods-config/attr_filter/pre-proxy"
Wed Dec  5 04:44:41 2018 : Debug :       relaxed = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     attr_filter attr_filter.post-proxy {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/etc/raddb/mods-config/attr_filter/post-proxy"
Wed Dec  5 04:44:41 2018 : Debug :       relaxed = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     attr_filter attr_filter.access_reject {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/etc/raddb/mods-config/attr_filter/access_reject"
Wed Dec  5 04:44:41 2018 : Debug :       relaxed = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     attr_filter attr_filter.access_challenge {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/etc/raddb/mods-config/attr_filter/access_challenge"
Wed Dec  5 04:44:41 2018 : Debug :       relaxed = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     attr_filter attr_filter.accounting_response {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/etc/raddb/mods-config/attr_filter/accounting_response"
Wed Dec  5 04:44:41 2018 : Debug :       relaxed = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_cache
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_cache"
Wed Dec  5 04:44:41 2018 : Debug :     cache cache_eap {
Wed Dec  5 04:44:41 2018 : Debug :       driver = "rlm_cache_rbtree"
Wed Dec  5 04:44:41 2018 : Debug :       ttl = 15
Wed Dec  5 04:44:41 2018 : Debug :       max_entries = 0
Wed Dec  5 04:44:41 2018 : Debug :       epoch = 0
Wed Dec  5 04:44:41 2018 : Debug :       add_stats = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_chap
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_chap"
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_client"
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_detail
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_detail"
Wed Dec  5 04:44:41 2018 : Debug :     detail {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-S
rc-IPv6-Address}}/detail-%Y-%m-%d"
Wed Dec  5 04:44:41 2018 : Debug :       header = "%t"
Wed Dec  5 04:44:41 2018 : Debug :       permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :       locking = no
Wed Dec  5 04:44:41 2018 : Debug :       escape_filenames = no
Wed Dec  5 04:44:41 2018 : Debug :       log_packet_header = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     detail auth_log {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-S
rc-IPv6-Address}}/auth-detail-%Y-%m-%d"
Wed Dec  5 04:44:41 2018 : Debug :       header = "%t"
Wed Dec  5 04:44:41 2018 : Debug :       permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :       locking = no
Wed Dec  5 04:44:41 2018 : Debug :       escape_filenames = no
Wed Dec  5 04:44:41 2018 : Debug :       log_packet_header = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     detail reply_log {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-S
rc-IPv6-Address}}/reply-detail-%Y-%m-%d"
Wed Dec  5 04:44:41 2018 : Debug :       header = "%t"
Wed Dec  5 04:44:41 2018 : Debug :       permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :       locking = no
Wed Dec  5 04:44:41 2018 : Debug :       escape_filenames = no
Wed Dec  5 04:44:41 2018 : Debug :       log_packet_header = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     detail pre_proxy_log {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-S
rc-IPv6-Address}}/pre-proxy-detail-%Y-%m-%d"
Wed Dec  5 04:44:41 2018 : Debug :       header = "%t"
Wed Dec  5 04:44:41 2018 : Debug :       permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :       locking = no
Wed Dec  5 04:44:41 2018 : Debug :       escape_filenames = no
Wed Dec  5 04:44:41 2018 : Debug :       log_packet_header = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     detail post_proxy_log {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-S
rc-IPv6-Address}}/post-proxy-detail-%Y-%m-%d"
Wed Dec  5 04:44:41 2018 : Debug :       header = "%t"
Wed Dec  5 04:44:41 2018 : Debug :       permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :       locking = no
Wed Dec  5 04:44:41 2018 : Debug :       escape_filenames = no
Wed Dec  5 04:44:41 2018 : Debug :       log_packet_header = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_digest
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_digest"
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_dhcpv4"
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_eap
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_eap"
Wed Dec  5 04:44:41 2018 : Debug :     eap inner-eap {
Wed Dec  5 04:44:41 2018 : Debug :       default_eap_type = mschapv2
Wed Dec  5 04:44:41 2018 : Debug :       type = md5
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_eap_md5
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_eap_md5"
Wed Dec  5 04:44:41 2018 : Debug :       type = gtc
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_eap_gtc
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_eap_gtc"
Wed Dec  5 04:44:41 2018 : Debug :       gtc {
Wed Dec  5 04:44:41 2018 : Debug :         challenge = "Password: "
Wed Dec  5 04:44:41 2018 : Debug :         auth_type = PAP
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       type = mschapv2
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_eap_mschapv2
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_eap_mschapv2"
Wed Dec  5 04:44:41 2018 : Debug :       mschapv2 {
Wed Dec  5 04:44:41 2018 : Debug :         with_ntdomain_hack = no
Wed Dec  5 04:44:41 2018 : Debug :         auth_type = mschap
Wed Dec  5 04:44:41 2018 : Debug :         send_error = no
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       type = tls
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_eap_tls
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_eap_tls"
Wed Dec  5 04:44:41 2018 : Debug :       tls {
Wed Dec  5 04:44:41 2018 : Debug :         tls = "tls-peer"
Wed Dec  5 04:44:41 2018 : Debug :         require_client_cert = yes
Wed Dec  5 04:44:41 2018 : Debug :         include_length = yes
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       ignore_unknown_eap_types = no
Wed Dec  5 04:44:41 2018 : Debug :       cisco_accounting_username_bug = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_exec"
Wed Dec  5 04:44:41 2018 : Debug :     exec echo {
Wed Dec  5 04:44:41 2018 : Debug :       wait = yes
Wed Dec  5 04:44:41 2018 : Debug :       program = "/bin/echo %{User-Name}"
Wed Dec  5 04:44:41 2018 : Debug :       input_pairs = "request"
Wed Dec  5 04:44:41 2018 : Debug :       output_pairs = "reply"
Wed Dec  5 04:44:41 2018 : Debug :       shell_escape = yes
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_escape"
Wed Dec  5 04:44:41 2018 : Debug :     escape {
Wed Dec  5 04:44:41 2018 : Debug :       safe_characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-
_: /äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ"
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     exec {
Wed Dec  5 04:44:41 2018 : Debug :       wait = no
Wed Dec  5 04:44:41 2018 : Debug :       input_pairs = "request"
Wed Dec  5 04:44:41 2018 : Debug :       shell_escape = yes
Wed Dec  5 04:44:41 2018 : Debug :       timeout = 10
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_expiration
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_expiration"
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_expr"
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_files
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_files"
Wed Dec  5 04:44:41 2018 : Debug :     files {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/etc/raddb/mods-config/files/authorize"
Wed Dec  5 04:44:41 2018 : Debug :       acctusersfile = "/usr/local/etc/raddb/mods-config/files/accounting"
Wed Dec  5 04:44:41 2018 : Debug :       preproxy_usersfile = "/usr/local/etc/raddb/mods-config/files/pre-proxy"
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_linelog"
Wed Dec  5 04:44:41 2018 : Debug :     linelog {
Wed Dec  5 04:44:41 2018 : Debug :       destination = "file"
Wed Dec  5 04:44:41 2018 : Debug :       delimiter = " "
Wed Dec  5 04:44:41 2018 : Debug :       file {
Wed Dec  5 04:44:41 2018 : Debug :         filename = "/usr/local/var/log/radius/linelog"
Wed Dec  5 04:44:41 2018 : Debug :         permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :         escape_filenames = no
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       syslog {
Wed Dec  5 04:44:41 2018 : Debug :         severity = "info"
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       unix {
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       tcp {
Wed Dec  5 04:44:41 2018 : Debug :         port = 514
Wed Dec  5 04:44:41 2018 : Debug :         timeout = 2.000000
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       udp {
Wed Dec  5 04:44:41 2018 : Debug :         port = 514
Wed Dec  5 04:44:41 2018 : Debug :         timeout = 2.000000
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     linelog log_accounting {
Wed Dec  5 04:44:41 2018 : Debug :       destination = "file"
Wed Dec  5 04:44:41 2018 : Debug :       delimiter = " "
Wed Dec  5 04:44:41 2018 : Debug :       file {
Wed Dec  5 04:44:41 2018 : Debug :         filename = "/usr/local/var/log/radius/linelog-accounting"
Wed Dec  5 04:44:41 2018 : Debug :         permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :         escape_filenames = no
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       syslog {
Wed Dec  5 04:44:41 2018 : Debug :         severity = "info"
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       unix {
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       tcp {
Wed Dec  5 04:44:41 2018 : Debug :         timeout = 1000.000000
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       udp {
Wed Dec  5 04:44:41 2018 : Debug :         timeout = 1000.000000
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_logintime
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_logintime"
Wed Dec  5 04:44:41 2018 : Debug :     logintime {
Wed Dec  5 04:44:41 2018 : Debug :       minimum_timeout = 60
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_mschap"
Wed Dec  5 04:44:41 2018 : Debug :     mschap {
Wed Dec  5 04:44:41 2018 : Debug :       use_mppe = yes
Wed Dec  5 04:44:41 2018 : Debug :       require_encryption = no
Wed Dec  5 04:44:41 2018 : Debug :       require_strong = no
Wed Dec  5 04:44:41 2018 : Debug :       with_ntdomain_hack = yes
Wed Dec  5 04:44:41 2018 : Debug :       passchange {
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       allow_retry = yes
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     exec ntlm_auth {
Wed Dec  5 04:44:41 2018 : Debug :       wait = yes
Wed Dec  5 04:44:41 2018 : Debug :       program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN --username=%{mschap
:User-Name} --password=%{User-Password}"
Wed Dec  5 04:44:41 2018 : Debug :       shell_escape = yes
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_pam
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_pam"
Wed Dec  5 04:44:41 2018 : Debug :     pam {
Wed Dec  5 04:44:41 2018 : Debug :       pam_auth = "radiusd"
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_pap
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_pap"
Wed Dec  5 04:44:41 2018 : Debug :     pap {
Wed Dec  5 04:44:41 2018 : Debug :       normalise = yes
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_passwd
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_passwd"
Wed Dec  5 04:44:41 2018 : Debug :     passwd etc_passwd {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/etc/passwd"
Wed Dec  5 04:44:41 2018 : Debug :       format = "*User-Name:Crypt-Password:"
Wed Dec  5 04:44:41 2018 : Debug :       delimiter = ":"
Wed Dec  5 04:44:41 2018 : Debug :       ignore_nislike = no
Wed Dec  5 04:44:41 2018 : Debug :       ignore_empty = yes
Wed Dec  5 04:44:41 2018 : Debug :       allow_multiple_keys = no
Wed Dec  5 04:44:41 2018 : Debug :       hash_size = 100
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_radius
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_radius"
Wed Dec  5 04:44:41 2018 : Debug :     radius {
Wed Dec  5 04:44:41 2018 : Debug :       transport = udp
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_radius_udp
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_radius_udp"
Wed Dec  5 04:44:41 2018 : Debug :       udp {
Wed Dec  5 04:44:41 2018 : Debug :         ipaddr = 127.0.0.1
Wed Dec  5 04:44:41 2018 : Debug :         port = 1812
Wed Dec  5 04:44:41 2018 : Debug :         secret = "testing123"
Wed Dec  5 04:44:41 2018 : Debug :         max_packet_size = 4096
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       type = Access-Request
Wed Dec  5 04:44:41 2018 : Debug :       type = Accounting-Request
Wed Dec  5 04:44:41 2018 : Debug :       status_checks {
Wed Dec  5 04:44:41 2018 : Debug :         type = Status-Server
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       max_connections = 32
Wed Dec  5 04:44:41 2018 : Debug :       max_attributes = 255
Wed Dec  5 04:44:41 2018 : Debug :       connection {
Wed Dec  5 04:44:41 2018 : Debug :         connect_timeout = 5.000000
Wed Dec  5 04:44:41 2018 : Debug :         reconnect_delay = 5.000000
Wed Dec  5 04:44:41 2018 : Debug :         idle_timeout = 5.000000
Wed Dec  5 04:44:41 2018 : Debug :         zombie_period = 10.000000
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       Access-Request {
Wed Dec  5 04:44:41 2018 : Debug :         initial_retransmission_time = 2
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_time = 16
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_count = 2
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_duration = 30
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       Accounting-Request {
Wed Dec  5 04:44:41 2018 : Debug :         initial_retransmission_time = 2
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_time = 16
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_count = 5
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_duration = 30
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :       Status-Server {
Wed Dec  5 04:44:41 2018 : Debug :         initial_retransmission_time = 2
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_time = 16
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_count = 5
Wed Dec  5 04:44:41 2018 : Debug :         maximum_retransmission_duration = 30
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_radutmp
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_radutmp"
Wed Dec  5 04:44:41 2018 : Debug :     radutmp {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/var/log/radius/radutmp"
Wed Dec  5 04:44:41 2018 : Debug :       username = "%{User-Name}"
Wed Dec  5 04:44:41 2018 : Debug :       case_sensitive = yes
Wed Dec  5 04:44:41 2018 : Debug :       check_with_nas = yes
Wed Dec  5 04:44:41 2018 : Debug :       permissions = 384
Wed Dec  5 04:44:41 2018 : Debug :       caller_id = yes
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_soh
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_soh"
Wed Dec  5 04:44:41 2018 : Debug :     soh {
Wed Dec  5 04:44:41 2018 : Debug :       dhcp = yes
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :     radutmp sradutmp {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/var/log/radius/sradutmp"
Wed Dec  5 04:44:41 2018 : Debug :       username = "%{User-Name}"
Wed Dec  5 04:44:41 2018 : Debug :       case_sensitive = yes
Wed Dec  5 04:44:41 2018 : Debug :       check_with_nas = yes
Wed Dec  5 04:44:41 2018 : Debug :       permissions = 420
Wed Dec  5 04:44:41 2018 : Debug :       caller_id = no
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_stats
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_stats"
Wed Dec  5 04:44:41 2018 : Debug :     stats {
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_unix
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_unix"
Wed Dec  5 04:44:41 2018 : Debug :     unix {
Wed Dec  5 04:44:41 2018 : Debug :       radwtmp = "/usr/local/var/log/radius/radwtmp"
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug : Creating attribute Unix-Group
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_unpack
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_unpack"
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_utf8"
Wed Dec  5 04:44:41 2018 : Debug :     passwd mac2ip {
Wed Dec  5 04:44:41 2018 : Debug :       filename = "/usr/local/etc/raddb/mods-config/passwd/mac2ip"
Wed Dec  5 04:44:41 2018 : Debug :       format = "*DHCP-Client-Hardware-Address:=DHCP-Your-IP-Address"
Wed Dec  5 04:44:41 2018 : Debug :       delimiter = ","
Wed Dec  5 04:44:41 2018 : Debug :       ignore_nislike = yes
Wed Dec  5 04:44:41 2018 : Debug :       ignore_empty = yes
Wed Dec  5 04:44:41 2018 : Debug :       allow_multiple_keys = no
Wed Dec  5 04:44:41 2018 : Debug :       hash_size = 100
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Info  : libfreeradius-redis: libhiredis version: 0.12.1
Wed Dec  5 04:44:41 2018 : Debug : Loading dictionary rlm_redis_ippool
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_redis_ippool"
Wed Dec  5 04:44:41 2018 : Debug :     redis_ippool {
Wed Dec  5 04:44:41 2018 : Debug :       copy_on_update = yes
Wed Dec  5 04:44:41 2018 : Debug :       redis {
Wed Dec  5 04:44:41 2018 : Debug :         server = "10.43.16.224"
Wed Dec  5 04:44:41 2018 : Debug :         port = 6379
Wed Dec  5 04:44:41 2018 : Debug :         database = 0
Wed Dec  5 04:44:41 2018 : Debug :         max_nodes = 20
Wed Dec  5 04:44:41 2018 : Debug :         max_alt = 3
Wed Dec  5 04:44:41 2018 : Debug :         max_redirects = 2
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Info  : libfreeradius-redis: libhiredis version: 0.12.1
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_redis"
Wed Dec  5 04:44:41 2018 : Debug :     redis {
Wed Dec  5 04:44:41 2018 : Debug :       server = "10.43.16.224"
Wed Dec  5 04:44:41 2018 : Debug :       port = 6379
Wed Dec  5 04:44:41 2018 : Debug :       database = 0
Wed Dec  5 04:44:41 2018 : Debug :       max_nodes = 20
Wed Dec  5 04:44:41 2018 : Debug :       max_alt = 3
Wed Dec  5 04:44:41 2018 : Debug :       max_redirects = 2
Wed Dec  5 04:44:41 2018 : Debug :     }
Wed Dec  5 04:44:41 2018 : Debug :   instantiate {
Wed Dec  5 04:44:41 2018 : Debug :   }
Wed Dec  5 04:44:41 2018 : Debug :  } # modules
Wed Dec  5 04:44:41 2018 : Debug : #### Instantiating listeners ####
Wed Dec  5 04:44:41 2018 : Debug : Compiling policies in server dhcp { ... }
Wed Dec  5 04:44:41 2018 : Debug : compiling - recv DHCP-Discover {...}
Wed Dec  5 04:44:41 2018 : Debug : compiling - recv DHCP-Request {...}
Wed Dec  5 04:44:41 2018 : Debug : compiling - recv DHCP-Decline {...}
Wed Dec  5 04:44:41 2018 : Debug : compiling - recv DHCP-Inform {...}
Wed Dec  5 04:44:41 2018 : Debug : compiling - recv DHCP-Release {...}
Wed Dec  5 04:44:41 2018 : Debug : compiling - recv DHCP-Lease-Query {...}
Wed Dec  5 04:44:41 2018 : Debug : #### Instantiating modules ####
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "attr_filter.access_challenge"
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/attr_filter/access_challenge
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "attr_filter.access_reject"
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/attr_filter/access_reject
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "attr_filter.accounting_response"
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/attr_filter/accounting_response
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "attr_filter.post-proxy"
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/attr_filter/post-proxy
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "attr_filter.pre-proxy"
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/attr_filter/pre-proxy
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "auth_log"
Wed Dec  5 04:44:41 2018 : Debug : rlm_detail (auth_log) - 'User-Password' suppressed, will not appear in detail output
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "cache_eap"
Wed Dec  5 04:44:41 2018 : Info  : Loaded module "rlm_cache_rbtree"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "detail"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "etc_passwd"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "expiration"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "fail"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "files"
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/files/authorize
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/files/accounting
Wed Dec  5 04:44:41 2018 : Debug : Reading file /usr/local/etc/raddb/mods-config/files/pre-proxy
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "handled"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "inner-eap"
Wed Dec  5 04:44:41 2018 : Debug :       tls-config tls-peer {
Wed Dec  5 04:44:41 2018 : Debug :         auto_chain = yes
Wed Dec  5 04:44:41 2018 : Debug :         chain {
Wed Dec  5 04:44:41 2018 : Debug :           format = pem
Wed Dec  5 04:44:41 2018 : Debug :           certificate_file = "/usr/local/etc/raddb/certs/rsa/server.pem"
Wed Dec  5 04:44:41 2018 : Debug :           private_key_password = <<< secret >>>
Wed Dec  5 04:44:41 2018 : Debug :           private_key_file = "/usr/local/etc/raddb/certs/rsa/server.key"
Wed Dec  5 04:44:41 2018 : Debug :           ca_file = "/usr/local/etc/raddb/certs/rsa/ca.pem"
Wed Dec  5 04:44:41 2018 : Debug :           verify_mode = hard
Wed Dec  5 04:44:41 2018 : Debug :           include_root_ca = no
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Debug :         verify_depth = 0
Wed Dec  5 04:44:41 2018 : Debug :         ca_path = "/usr/local/etc/raddb/certs"
Wed Dec  5 04:44:41 2018 : Debug :         ca_file = "/usr/local/etc/raddb/certs/rsa/ca.pem"
Wed Dec  5 04:44:41 2018 : Debug :         dh_file = "/usr/local/etc/raddb/certs/dh"
Wed Dec  5 04:44:41 2018 : Debug :         fragment_size = 16384
Wed Dec  5 04:44:41 2018 : Debug :         check_crl = no
Wed Dec  5 04:44:41 2018 : Debug :         cipher_server_preference = yes
Wed Dec  5 04:44:41 2018 : Debug :         allow_renegotiation = no
Wed Dec  5 04:44:41 2018 : Debug :         ecdh_curve = "prime256v1"
Wed Dec  5 04:44:41 2018 : Debug :         tls_min_version = 1.000000
Wed Dec  5 04:44:41 2018 : Debug :         cache {
Wed Dec  5 04:44:41 2018 : Debug :           lifetime = 86400
Wed Dec  5 04:44:41 2018 : Debug :           verify = no
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Debug :         verify {
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Debug :         ocsp {
Wed Dec  5 04:44:41 2018 : Debug :           enable = no
Wed Dec  5 04:44:41 2018 : Debug :           override_cert_url = no
Wed Dec  5 04:44:41 2018 : Debug :           use_nonce = yes
Wed Dec  5 04:44:41 2018 : Debug :           timeout = 0
Wed Dec  5 04:44:41 2018 : Debug :           softfail = no
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Debug :         staple {
Wed Dec  5 04:44:41 2018 : Debug :           enable = no
Wed Dec  5 04:44:41 2018 : Debug :           override_cert_url = no
Wed Dec  5 04:44:41 2018 : Debug :           use_nonce = yes
Wed Dec  5 04:44:41 2018 : Debug :           timeout = 0
Wed Dec  5 04:44:41 2018 : Debug :           softfail = no
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "invalid"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "linelog"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "log_accounting"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "logintime"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "mac2ip"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "mschap"
Wed Dec  5 04:44:41 2018 : Debug : mschap: using internal authentication
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "noop"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "notfound"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "ok"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "pam"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "post_proxy_log"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "pre_proxy_log"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "radius"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "redis"
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Initialising connection pool
Wed Dec  5 04:44:41 2018 : Debug :       pool {
Wed Dec  5 04:44:41 2018 : Debug :         start = 4
Wed Dec  5 04:44:41 2018 : Debug :         min = 1
Wed Dec  5 04:44:41 2018 : Debug :         max = 4
Wed Dec  5 04:44:41 2018 : Debug :         max_pending = 0
Wed Dec  5 04:44:41 2018 : Debug :         spare = 1
Wed Dec  5 04:44:41 2018 : Debug :         uses = 0
Wed Dec  5 04:44:41 2018 : Debug :         lifetime = 86400
Wed Dec  5 04:44:41 2018 : Debug :         cleanup_interval = 300
Wed Dec  5 04:44:41 2018 : Debug :         idle_timeout = 600
Wed Dec  5 04:44:41 2018 : Debug :         connect_timeout = 3.000000
Wed Dec  5 04:44:41 2018 : Debug :         held_trigger_min = 0.000000
Wed Dec  5 04:44:41 2018 : Debug :         held_trigger_max = 0.500000
Wed Dec  5 04:44:41 2018 : Debug :         retry_delay = 30
Wed Dec  5 04:44:41 2018 : Debug :         spread = no
Wed Dec  5 04:44:41 2018 : Debug :       }
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Opening additional connection (0), 1 of 4 pending slots used
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1]: Connecting node to 10.43.16.224:6379
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Opening additional connection (1), 1 of 3 pending slots used
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1]: Connecting node to 10.43.16.224:6379
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Opening additional connection (2), 1 of 2 pending slots used
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1]: Connecting node to 10.43.16.224:6379
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Opening additional connection (3), 1 of 1 pending slots used
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1]: Connecting node to 10.43.16.224:6379
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Reserved connection (3)
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis): Bootstrap server "10.43.16.224" returned: ERR This instance has cluster
support disabled
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Released connection (3)
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "redis_ippool"
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Initialising connection pool
Wed Dec  5 04:44:41 2018 : Debug :         pool {
Wed Dec  5 04:44:41 2018 : Debug :           start = 0
Wed Dec  5 04:44:41 2018 : Debug :           min = 4
Wed Dec  5 04:44:41 2018 : Debug :           max = 4
Wed Dec  5 04:44:41 2018 : Debug :           max_pending = 0
Wed Dec  5 04:44:41 2018 : Debug :           spare = 1
Wed Dec  5 04:44:41 2018 : Debug :           uses = 0
Wed Dec  5 04:44:41 2018 : Debug :           lifetime = 0
Wed Dec  5 04:44:41 2018 : Debug :           cleanup_interval = 30
Wed Dec  5 04:44:41 2018 : Debug :           idle_timeout = 60
Wed Dec  5 04:44:41 2018 : Debug :           connect_timeout = 3.000000
Wed Dec  5 04:44:41 2018 : Debug :           held_trigger_min = 0.000000
Wed Dec  5 04:44:41 2018 : Debug :           held_trigger_max = 0.500000
Wed Dec  5 04:44:41 2018 : Debug :           retry_delay = 30
Wed Dec  5 04:44:41 2018 : Debug :           spread = no
Wed Dec  5 04:44:41 2018 : Debug :         }
Wed Dec  5 04:44:41 2018 : Warn  : rlm_redis (redis) [1] - Ignoring "spare = 1", forcing to "spare = 0"
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - 0 of 0 connections in use.  You  may need to increase "spare"
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Opening additional connection (0), 1 of 4 pending slots used
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1]: Connecting node to 10.43.16.224:6379
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Reserved connection (0)
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Released connection (0)
Wed Dec  5 04:44:41 2018 : Info  : rlm_redis (redis) [1] - Need 3 more connections to reach min connections (4)
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1] - Opening additional connection (1), 1 of 3 pending slots used
Wed Dec  5 04:44:41 2018 : Debug : rlm_redis (redis) [1]: Connecting node to 10.43.16.224:6379
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "reject"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "reply_log"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "stats"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "updated"
Wed Dec  5 04:44:41 2018 : Debug : Instantiating module "userlock"
Wed Dec  5 04:44:41 2018 : Debug : [1] radius - Connection initialising
Wed Dec  5 04:44:41 2018 : Debug : [1] radius - Connection initialised
Wed Dec  5 04:44:41 2018 : Debug : Scheduler created in single-threaded mode
Wed Dec  5 04:44:41 2018 : Debug : #### Opening listener interfaces ####
Wed Dec  5 04:44:41 2018 : Debug : Listening on dhcpv4 address proto_dhcpv4_udp server 10.43.18.92 port 67 bound to virtual s
erver dhcp
Wed Dec  5 04:44:41 2018 : Debug : Waking up in 4.9 seconds.
Wed Dec  5 04:44:41 2018 : Debug : radius - Connection open - proto udp local 0.0.0.0 port 41575 remote 127.0.0.1 port 1812
Wed Dec  5 04:44:41 2018 : Debug : radius - Allocated Status-Server ID 0 for status checks on connection proto udp local 0.0.
0.0 port 41575 remote 127.0.0.1 port 1812
Wed Dec  5 04:44:41 2018 : Debug : radius - Setting idle timeout to +5.000000 for connection proto udp local 0.0.0.0 port 415
75 remote 127.0.0.1 port 1812
Wed Dec  5 04:44:41 2018 : Debug : [1] radius - Connection established
Wed Dec  5 04:44:41 2018 : Debug : Waking up in 4.9 seconds.
Wed Dec  5 04:44:41 2018 : Debug : Waking up in 4.9 seconds.
Wed Dec  5 04:44:46 2018 : Debug : radius - Idle timeout for connection proto udp local 0.0.0.0 port 41575 remote 127.0.0.1 p
ort 1812
Wed Dec  5 04:44:46 2018 : Debug : [1] radius - Closing connection (18)
Wed Dec  5 04:44:46 2018 : Debug : radius - Connection closed - proto udp local 0.0.0.0 port 41575 remote 127.0.0.1 port 1812
Wed Dec  5 04:44:46 2018 : Info  : Ready to process requests
Wed Dec  5 04:44:49 2018 : Debug : proto_dhcpv4_udp - Received DHCP-Discover XID 00000000 length 259 proto_dhcpv4_udp server
10.43.18.92 port 67
Wed Dec  5 04:44:49 2018 : Debug : Network received packet size 259
Wed Dec  5 04:44:49 2018 : Debug : Resetting worker 30 cleanup timer to +0s
Wed Dec  5 04:44:49 2018 : Debug : (0)  running request
(0)  Wed Dec  5 04:44:49 2018 : Debug : Received DHCP-Discover XID 00000000 from 127.0.0.1:67 to 10.43.18.92:67 via lo
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Opcode = Client-Message
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Hardware-Type = Ethernet
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Hardware-Address-Length = 6
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Hop-Count = 1
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Transaction-Id = 0
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Number-of-Seconds = 0
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Flags = 0
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Client-IP-Address = 127.0.0.1
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Your-IP-Address = 0.0.0.0
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Server-IP-Address = 0.0.0.0
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Gateway-IP-Address = 127.0.0.1
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Client-Hardware-Address = 00:a1:bc:00:10:01
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Message-Type = DHCP-Discover
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-DHCP-Server-Identifier = 10.43.18.92
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Client-Identifier = 0x0100a1bc000001
(0)  Wed Dec  5 04:44:49 2018 : Debug : Running 'recv DHCP-Discover' from file /usr/local/etc/raddb/sites-enabled/dhcp
(0)  Wed Dec  5 04:44:49 2018 : Debug : recv DHCP-Discover {
(0)  Wed Dec  5 04:44:49 2018 : Debug :   update reply {
(0)  Wed Dec  5 04:44:49 2018 : Debug :     DHCP-Message-Type = DHCP-Offer
(0)  Wed Dec  5 04:44:49 2018 : Debug :   } # update reply (noop)
(0)  Wed Dec  5 04:44:49 2018 : Debug :   update reply {
(0)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-Subnet-Mask = 255.255.255.0
(0)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-Router-Address = 10.43.18.92
(0)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-IP-Address-Lease-Time = 900
(0)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-DHCP-Server-Identifier = 10.43.18.92
(0)  Wed Dec  5 04:44:49 2018 : Debug :   } # update reply (noop)
(0)  Wed Dec  5 04:44:49 2018 : Debug :   update control {
(0)  Wed Dec  5 04:44:49 2018 : Debug :     &Pool-Name := "local"
(0)  Wed Dec  5 04:44:49 2018 : Debug :   } # update control (noop)
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - Allocating lease from pool "local", to "00:a1:bc:00:10:01", expires
in 900s
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - Reserved connection (1)
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - [1] >>> Sending command(s) to 10.43.16.224:6379
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - [1] <<< Returned: success
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - Released connection (1)
(0)  Wed Dec  5 04:44:49 2018 : Info  :   redis_ippool - Need 2 more connections to reach min connections (4)
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - Opening additional connection (2), 1 of 2 pending slots used
Wed Dec  5 04:44:49 2018 : Debug : rlm_redis (redis) [1]: Connecting node to 10.43.16.224:6379
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - &reply:DHCP-Your-IP-Address := 192.168.24.0
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - &reply:DHCP-IP-Address-Lease-Time := 663
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - IP address lease allocated
(0)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool (updated)
(0)  Wed Dec  5 04:44:49 2018 : Debug :   ok (ok)
(0)  Wed Dec  5 04:44:49 2018 : Debug : } # recv DHCP-Discover (updated)
(0)  Wed Dec  5 04:44:49 2018 : Debug : Sent DHCP-Offer XID 00000000 from 10.43.18.92:67 to 127.0.0.1:67 via lo
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Message-Type = DHCP-Offer
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Subnet-Mask = 255.255.255.0
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Router-Address = 10.43.18.92
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-IP-Address-Lease-Time := 663
(0)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-DHCP-Server-Identifier = 10.43.18.92
Wed Dec  5 04:44:49 2018 : Debug : (0)  done request
Wed Dec  5 04:44:49 2018 : Debug : (0)  finished request.
Wed Dec  5 04:44:49 2018 : Info  : Ready to process requests
Wed Dec  5 04:44:49 2018 : Debug : Reply will be unicast to CIADDR from original packet.
Wed Dec  5 04:44:49 2018 : Debug : proto_dhcpv4_udp - cleaning up ID 1
Wed Dec  5 04:44:49 2018 : Info  : Ready to process requests
Wed Dec  5 04:44:49 2018 : Debug : proto_dhcpv4_udp - Received DHCP-Request XID 00000000 length 265 proto_dhcpv4_udp server 1
0.43.18.92 port 67
Wed Dec  5 04:44:49 2018 : Debug : Network received packet size 265
Wed Dec  5 04:44:49 2018 : Debug : Resetting worker 30 cleanup timer to +0s
Wed Dec  5 04:44:49 2018 : Debug : (1)  running request
(1)  Wed Dec  5 04:44:49 2018 : Debug : Received DHCP-Request XID 00000000 from 127.0.0.1:67 to 10.43.18.92:67 via lo
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Opcode = Client-Message
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Hardware-Type = Ethernet
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Hardware-Address-Length = 6
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Hop-Count = 1
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Transaction-Id = 0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Number-of-Seconds = 0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Flags = 0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Client-IP-Address = 127.0.0.1
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Your-IP-Address = 0.0.0.0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Server-IP-Address = 0.0.0.0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Gateway-IP-Address = 127.0.0.1
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Client-Hardware-Address = 00:a1:bc:00:10:01
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Requested-IP-Address = 192.168.24.0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Message-Type = DHCP-Request
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-DHCP-Server-Identifier = 10.43.18.92
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Client-Identifier = 0x0100a1bc000001
(1)  Wed Dec  5 04:44:49 2018 : Debug : Running 'recv DHCP-Request' from file /usr/local/etc/raddb/sites-enabled/dhcp
(1)  Wed Dec  5 04:44:49 2018 : Debug : recv DHCP-Request {
(1)  Wed Dec  5 04:44:49 2018 : Debug :   update reply {
(1)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-Message-Type = DHCP-Ack
(1)  Wed Dec  5 04:44:49 2018 : Debug :   } # update reply (noop)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   update reply {
(1)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-Subnet-Mask = 255.255.255.0
(1)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-Router-Address = 10.43.18.92
(1)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-IP-Address-Lease-Time = 900
(1)  Wed Dec  5 04:44:49 2018 : Debug :     &DHCP-DHCP-Server-Identifier = 10.43.18.92
(1)  Wed Dec  5 04:44:49 2018 : Debug :   } # update reply (noop)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   update control {
(1)  Wed Dec  5 04:44:49 2018 : Debug :     &Pool-Name := "local"
(1)  Wed Dec  5 04:44:49 2018 : Debug :   } # update control (noop)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - Allocating lease from pool "local", to "00:a1:bc:00:10:01", expires
in 900s
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - Reserved connection (2)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - [1] >>> Sending command(s) to 10.43.16.224:6379
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - [1] <<< Returned: success
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - Released connection (2)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - &reply:DHCP-Your-IP-Address := 192.168.24.0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - &reply:DHCP-IP-Address-Lease-Time := 663
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool - IP address lease allocated
(1)  Wed Dec  5 04:44:49 2018 : Debug :   redis_ippool (updated)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   if (updated && "0x%{redis: GET %{control:Pool-Name}:option}") {
(1)  Wed Dec  5 04:44:49 2018 : Debug :     EXPAND 0x%{redis: GET %{control:Pool-Name}:option}
(1)  Wed Dec  5 04:44:49 2018 : Debug :     Reserved connection (3)
(1)  Wed Dec  5 04:44:49 2018 : Debug :     [1] >>> Sending command(s) to 10.43.16.224:6379
(1)  Wed Dec  5 04:44:49 2018 : Debug :     Executing command: GET
(1)  Wed Dec  5 04:44:49 2018 : Debug :     With arguments
(1)  Wed Dec  5 04:44:49 2018 : Debug :       [1] local:option
(1)  Wed Dec  5 04:44:49 2018 : Debug :     [1] <<< Returned: success
(1)  Wed Dec  5 04:44:49 2018 : Debug :     Released connection (3)
(1)  Wed Dec  5 04:44:49 2018 : Debug :        --> 0x010300a0bc0209555400000000000007030e555400000000000003070307001604115554
5f332e372e332e372e32322e62696e05040a4fff0f0c0200010e010014530100020103110500440044070043004301000201030606c0a8640018070050005
001010201060a000000080101020106ac1000000c0101020106c0a80000100101020106a9fe00001001010201067f00000008330c55543200000000000000
29083b0e55543200000000000307030600003c115554325f332e372e332e362e302e62696e3d040a4fff06
(1)  Wed Dec  5 04:44:49 2018 : Debug :     update reply {
(1)  Wed Dec  5 04:44:49 2018 : Debug :       EXPAND %{control:Pool-Name}
(1)  Wed Dec  5 04:44:49 2018 : Debug :         --> local
(1)  Wed Dec  5 04:44:49 2018 : Debug :       Reserved connection (3)
(1)  Wed Dec  5 04:44:49 2018 : Debug :       [1] >>> Sending command(s) to 10.43.16.224:6379
(1)  Wed Dec  5 04:44:49 2018 : Debug :       Executing command: GET
(1)  Wed Dec  5 04:44:49 2018 : Debug :       With arguments
(1)  Wed Dec  5 04:44:49 2018 : Debug :         [1] local:option
(1)  Wed Dec  5 04:44:49 2018 : Debug :       [1] <<< Returned: success
(1)  Wed Dec  5 04:44:49 2018 : Debug :       Released connection (3)
(1)  Wed Dec  5 04:44:49 2018 : Debug :       EXPAND %{redis: GET %{control:Pool-Name}:option}
(1)  Wed Dec  5 04:44:49 2018 : Debug :             (%{redis: GET local:option})
(1)  Wed Dec  5 04:44:49 2018 : Debug :         --> 010300a0bc0209555400000000000007030e5554000000000000030703070016041155545
f332e372e332e372e32322e62696e05040a4fff0f0c0200010e010014530100020103110500440044070043004301000201030606c0a86400180700500050
01010201060a000000080101020106ac1000000c0101020106c0a80000100101020106a9fe00001001010201067f00000008330c555432000000000000002
9083b0e55543200000000000307030600003c115554325f332e372e332e362e302e62696e3d040a4fff06
(1)  Wed Dec  5 04:44:49 2018 : Debug :       &DHCP-Vendor = 0x30783031303330306130626330323039353535343030303030303030303030
30303730333065353535343030303030303030303030303033303730333037303031363034313135353534356633333265333732653333326533373265333
23332326536323639366530353034306134666666306630633032303030313065303130303134353330313030303230313033313130353030343430303434
30373030343330303433303130303032303130333036303663306138363430303138303730303530303035303031303130323031303630613030303030303
03830313031303230313036616331303030303030633031303130323031303663306138303030303130303130313032303130366139666530303030313030
31303130323031303637663030303030303038333330633535353433323030303030303030303030303030323930383362306535353534333230303030303
03030303030333037303330363030303033633131353535343332356633333265333732653333326533363265333032653632363936653364303430613466
66663036
(1)  Wed Dec  5 04:44:49 2018 : Debug :     } # update reply (noop)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   } # if (updated && "0x%{redis: GET %{control:Pool-Name}:option}") (noop)
(1)  Wed Dec  5 04:44:49 2018 : Debug :   ok (ok)
(1)  Wed Dec  5 04:44:49 2018 : Debug : } # recv DHCP-Request (updated)
(1)  Wed Dec  5 04:44:49 2018 : Debug : Sent DHCP-Ack XID 00000000 from 10.43.18.92:67 to 127.0.0.1:67 via lo
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Message-Type = DHCP-Ack
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Subnet-Mask = 255.255.255.0
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Router-Address = 10.43.18.92
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-IP-Address-Lease-Time := 663
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-DHCP-Server-Identifier = 10.43.18.92
(1)  Wed Dec  5 04:44:49 2018 : Debug :   &DHCP-Vendor = 0x307830313033303061306263303230393535353430303030303030303030303030
37303330653535353430303030303030303030303030333037303330373030313630343131353535343566333332653337326533333265333732653332333
23265363236393665303530343061346666663066306330323030303130653031303031343533303130303032303130333131303530303434303034343037
30303433303034333031303030323031303330363036633061383634303031383037303035303030353030313031303230313036306130303030303030383
03130313032303130366163313030303030306330313031303230313036633061383030303031303031303130323031303661396665303030303130303130
31303230313036376630303030303030383333306335353534333230303030303030303030303030303239303833623065353535343332303030303030303
03030303330373033303630303030336331313535353433323566333332653337326533333265333632653330326536323639366533643034306134666666
3036
Wed Dec  5 04:44:49 2018 : Debug : (1)  done request


Regards,
Nagamani Chinnapaiyan

-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
| Threaded
Open this post in threaded view
|

Re: Hex to Cleartext

arr2036


> On Dec 4, 2018, at 11:50 PM, Chinnapaiyan, Nagamani <[hidden email]> wrote:
>
> This “0x%{redis:…}” doesnot work for me.
>
> Lines in config file:
>        if(updated && "0x%{redis: GET %{control:Pool-Name}:option}") {
>                update reply {
>                        &DHCP-Vendor = "0x%{redis: GET %{control:Pool-Name}:option}"
>                }
>        }
> It parses the if condition correctly.. but  the assignment part somehow it doesnot process it correctly.

You want:

update reply {
        &DHCP-Vendor = "%{bin:%{redis: GET %{control:Pool-Name}:option}}"
}

Bin takes a hex string and returns a buffer containing the binary data.  Bin doesn't require a 0x prefix, but will automatically strip one if it finds one.

It's a bit annoying needing to jump through these hoops though.  I'm guessing you're inserting the hexits into redis instead of the binary string?  The xlat is binary safe so you shouldn't need to do that...

-Arran
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
| Threaded
Open this post in threaded view
|

Re: Hex to Cleartext

Chinnapaiyan, Nagamani
In reply to this post by Chinnapaiyan, Nagamani
Thanks Arran... It works fine... I could not find this(bin) in the man page...


>It's a bit annoying needing to jump through these hoops though.  I'm guessing you're inserting the hexits into redis instead of the binary string?  The xlat is binary safe so you shouldn't need to do that...
Yes. redis db has hex digit string ...

Regards,
Nagamani Chinnapaiyan

-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
| Threaded
Open this post in threaded view
|

Re: Hex to Cleartext

arr2036


> On Dec 5, 2018, at 11:36 PM, Chinnapaiyan, Nagamani <[hidden email]> wrote:
>
> Thanks Arran... It works fine... I could not find this(bin) in the man page...

Yes, the documentation for v4.0.x is still in development, and as v4.0.x is still very much in development we make no apologies for the documentation being incomplete.

As this is a community driven project, you're welcome to submit a pull request on GitHub.  We might need a man "xlat" or man "expansions" to document the internal expansions.

-Arran
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
| Threaded
Open this post in threaded view
|

Re: Hex to Cleartext

Matthew Newton-3
On Thu, 2018-12-06 at 08:17 -0500, Arran Cudbard-Bell wrote:
> Yes, the documentation for v4.0.x is still in development, and as
> v4.0.x is still very much in development we make no apologies for the
> documentation being incomplete.
>
> As this is a community driven project, you're welcome to submit a
> pull request on GitHub.  We might need a man "xlat" or man
> "expansions" to document the internal expansions.

There's a couple of pages on the wiki I wrote a while back listing all
the expansions, but they're not updated for the master branch yet,
because, well, same reasons above.

--
Matthew

-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html