<div dir="ltr"><div><div><div>Hi there,<br><br></div>I've googled a while and found different solutions for the error message: [mschap] No Cleartext-Password configured. Cannot create LM-Password.<br><br></div>What I've done is, to establish the following setup: Debian 7.1, Samba3, OpenLDAP and freeradius 2.1.12 (everything on the same machine). A VPN gateway forwards the authentication requests to the freeradius-server.<br>
<br></div><div>While Samba is working fine, VPN-Users cannot login. The following debug output appears, when trying to:<br>radtest -t mschap testuser passwd <a href="http://127.0.0.1:18120">127.0.0.1:18120</a> 0 <secret> <passwd><br>
<br></div><div>Not sure, at which point I've to act. Would be great, if you could help<br><br><br></div><div><snip><br></div><div>Mon Jul 15 11:52:13 2013 : Info: FreeRADIUS Version 2.1.12, for host x86_64-pc-linux-gnu, built on Dec 16 2012 at 13:28:43<br>
Mon Jul 15 11:52:13 2013 : Info: Copyright (C) 1999-2009 The FreeRADIUS server project and contributors. <br>Mon Jul 15 11:52:13 2013 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A <br>Mon Jul 15 11:52:13 2013 : Info: PARTICULAR PURPOSE. <br>
Mon Jul 15 11:52:13 2013 : Info: You may redistribute copies of FreeRADIUS under the terms of the <br>Mon Jul 15 11:52:13 2013 : Info: GNU General Public License v2. <br>Mon Jul 15 11:52:13 2013 : Info: Starting - reading configuration files ...<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/radiusd.conf<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/proxy.conf<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/clients.conf<br>
Mon Jul 15 11:52:13 2013 : Debug: including files in directory /etc/freeradius/modules/<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/pam<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/realm<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/always<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/digest<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/etc_group<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/redis<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/attr_rewrite<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/rediswho<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/opendirectory<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/linelog<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/radutmp<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/replicate<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/echo<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/soh<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/unix<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/logintime<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/files<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/dynamic_clients<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/pap<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/otp<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/krb5<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/counter<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/ippool<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/smbpasswd<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/<a href="http://detail.example.com">detail.example.com</a><br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/inner-eap<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/attr_filter<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/sqlcounter_expire_on_login<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/chap<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/expr<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/sql_log<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/exec<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/detail.log<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/mac2ip<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/preprocess<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/policy<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/mac2vlan<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/acct_unique<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/mschap<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/detail<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/ldap<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/passwd<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/checkval<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/cui<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/wimax<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/perl<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/sradutmp<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/smsotp<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/expiration<br>
Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/modules/ntlm_auth<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/eap.conf<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/policy.conf<br>
Mon Jul 15 11:52:13 2013 : Debug: including files in directory /etc/freeradius/sites-enabled/<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/sites-enabled/default<br>Mon Jul 15 11:52:13 2013 : Debug: including configuration file /etc/freeradius/sites-enabled/inner-tunnel<br>
Mon Jul 15 11:52:13 2013 : Debug: main {<br>Mon Jul 15 11:52:13 2013 : Debug: user = "freerad"<br>Mon Jul 15 11:52:13 2013 : Debug: group = "freerad"<br>Mon Jul 15 11:52:13 2013 : Debug: allow_core_dumps = no<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: including dictionary file /etc/freeradius/dictionary<br>Mon Jul 15 11:52:13 2013 : Debug: main {<br>Mon Jul 15 11:52:13 2013 : Debug: name = "freeradius"<br>
Mon Jul 15 11:52:13 2013 : Debug: prefix = "/usr"<br>Mon Jul 15 11:52:13 2013 : Debug: localstatedir = "/var"<br>Mon Jul 15 11:52:13 2013 : Debug: sbindir = "/usr/sbin"<br>Mon Jul 15 11:52:13 2013 : Debug: logdir = "/var/log/freeradius"<br>
Mon Jul 15 11:52:13 2013 : Debug: run_dir = "/var/run/freeradius"<br>Mon Jul 15 11:52:13 2013 : Debug: libdir = "/usr/lib/freeradius"<br>Mon Jul 15 11:52:13 2013 : Debug: radacctdir = "/var/log/freeradius/radacct"<br>
Mon Jul 15 11:52:13 2013 : Debug: hostname_lookups = no<br>Mon Jul 15 11:52:13 2013 : Debug: max_request_time = 30<br>Mon Jul 15 11:52:13 2013 : Debug: cleanup_delay = 5<br>Mon Jul 15 11:52:13 2013 : Debug: max_requests = 1024<br>
Mon Jul 15 11:52:13 2013 : Debug: pidfile = "/var/run/freeradius/freeradius.pid"<br>Mon Jul 15 11:52:13 2013 : Debug: checkrad = "/usr/sbin/checkrad"<br>Mon Jul 15 11:52:13 2013 : Debug: debug_level = 0<br>
Mon Jul 15 11:52:13 2013 : Debug: proxy_requests = yes<br>Mon Jul 15 11:52:13 2013 : Debug: log {<br>Mon Jul 15 11:52:13 2013 : Debug: stripped_names = no<br>Mon Jul 15 11:52:13 2013 : Debug: auth = no<br>Mon Jul 15 11:52:13 2013 : Debug: auth_badpass = no<br>
Mon Jul 15 11:52:13 2013 : Debug: auth_goodpass = no<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: security {<br>Mon Jul 15 11:52:13 2013 : Debug: max_attributes = 200<br>Mon Jul 15 11:52:13 2013 : Debug: reject_delay = 1<br>
Mon Jul 15 11:52:13 2013 : Debug: status_server = yes<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: radiusd: #### Loading Realms and Home Servers ####<br>
Mon Jul 15 11:52:13 2013 : Debug: proxy server {<br>Mon Jul 15 11:52:13 2013 : Debug: retry_delay = 5<br>Mon Jul 15 11:52:13 2013 : Debug: retry_count = 3<br>Mon Jul 15 11:52:13 2013 : Debug: default_fallback = no<br>
Mon Jul 15 11:52:13 2013 : Debug: dead_time = 120<br>Mon Jul 15 11:52:13 2013 : Debug: wake_all_if_all_dead = no<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: home_server localhost {<br>
Mon Jul 15 11:52:13 2013 : Debug: ipaddr = 127.0.0.1<br>Mon Jul 15 11:52:13 2013 : Debug: port = 1812<br>Mon Jul 15 11:52:13 2013 : Debug: type = "auth"<br>Mon Jul 15 11:52:13 2013 : Debug: secret = "testing123"<br>
Mon Jul 15 11:52:13 2013 : Debug: response_window = 20<br>Mon Jul 15 11:52:13 2013 : Debug: max_outstanding = 65536<br>Mon Jul 15 11:52:13 2013 : Debug: require_message_authenticator = yes<br>Mon Jul 15 11:52:13 2013 : Debug: zombie_period = 40<br>
Mon Jul 15 11:52:13 2013 : Debug: status_check = "status-server"<br>Mon Jul 15 11:52:13 2013 : Debug: ping_interval = 30<br>Mon Jul 15 11:52:13 2013 : Debug: check_interval = 30<br>Mon Jul 15 11:52:13 2013 : Debug: num_answers_to_alive = 3<br>
Mon Jul 15 11:52:13 2013 : Debug: num_pings_to_alive = 3<br>Mon Jul 15 11:52:13 2013 : Debug: revive_interval = 120<br>Mon Jul 15 11:52:13 2013 : Debug: status_check_timeout = 4<br>Mon Jul 15 11:52:13 2013 : Debug: coa {<br>
Mon Jul 15 11:52:13 2013 : Debug: irt = 2<br>Mon Jul 15 11:52:13 2013 : Debug: mrt = 16<br>Mon Jul 15 11:52:13 2013 : Debug: mrc = 5<br>Mon Jul 15 11:52:13 2013 : Debug: mrd = 30<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: home_server_pool my_auth_failover {<br>Mon Jul 15 11:52:13 2013 : Debug: type = fail-over<br>Mon Jul 15 11:52:13 2013 : Debug: home_server = localhost<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: realm <a href="http://example.com">example.com</a> {<br>Mon Jul 15 11:52:13 2013 : Debug: auth_pool = my_auth_failover<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: realm LOCAL {<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: radiusd: #### Loading Clients ####<br>Mon Jul 15 11:52:13 2013 : Debug: client localhost {<br>
Mon Jul 15 11:52:13 2013 : Debug: ipaddr = 127.0.0.1<br>Mon Jul 15 11:52:13 2013 : Debug: require_message_authenticator = no<br>Mon Jul 15 11:52:13 2013 : Debug: secret = "secret"<br>Mon Jul 15 11:52:13 2013 : Debug: nastype = "other"<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: client <a href="http://192.168.1.0/24">192.168.1.0/24</a> {<br>Mon Jul 15 11:52:13 2013 : Debug: require_message_authenticator = no<br>Mon Jul 15 11:52:13 2013 : Debug: secret = "secret"<br>
Mon Jul 15 11:52:13 2013 : Debug: shortname = "private-network-1"<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: radiusd: #### Instantiating modules ####<br>Mon Jul 15 11:52:13 2013 : Debug: instantiate {<br>
Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_exec, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_exec<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "exec" from file /etc/freeradius/modules/exec<br>
Mon Jul 15 11:52:13 2013 : Debug: exec {<br>Mon Jul 15 11:52:13 2013 : Debug: wait = no<br>Mon Jul 15 11:52:13 2013 : Debug: input_pairs = "request"<br>Mon Jul 15 11:52:13 2013 : Debug: shell_escape = yes<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_expr, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_expr<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "expr" from file /etc/freeradius/modules/expr<br>
Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_expiration, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_expiration<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "expiration" from file /etc/freeradius/modules/expiration<br>
Mon Jul 15 11:52:13 2013 : Debug: expiration {<br>Mon Jul 15 11:52:13 2013 : Debug: reply-message = "Password Has Expired "<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_logintime, checking if it's valid)<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_logintime<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "logintime" from file /etc/freeradius/modules/logintime<br>Mon Jul 15 11:52:13 2013 : Debug: logintime {<br>
Mon Jul 15 11:52:13 2013 : Debug: reply-message = "You are calling outside your allowed timespan "<br>Mon Jul 15 11:52:13 2013 : Debug: minimum-timeout = 60<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: radiusd: #### Loading Virtual Servers ####<br>Mon Jul 15 11:52:13 2013 : Debug: server { # from file /etc/freeradius/radiusd.conf<br>Mon Jul 15 11:52:13 2013 : Debug: modules {<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Creating Auth-Type = digest<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Creating Post-Auth-Type = REJECT<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking authenticate {...} for more modules to load<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_pap, checking if it's valid)<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_pap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "pap" from file /etc/freeradius/modules/pap<br>Mon Jul 15 11:52:13 2013 : Debug: pap {<br>
Mon Jul 15 11:52:13 2013 : Debug: encryption_scheme = "auto"<br>Mon Jul 15 11:52:13 2013 : Debug: auto_header = yes<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_chap, checking if it's valid)<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_chap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "chap" from file /etc/freeradius/modules/chap<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_mschap, checking if it's valid)<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_mschap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "mschap" from file /etc/freeradius/modules/mschap<br>Mon Jul 15 11:52:13 2013 : Debug: mschap {<br>
Mon Jul 15 11:52:13 2013 : Debug: use_mppe = yes<br>Mon Jul 15 11:52:13 2013 : Debug: require_encryption = no<br>Mon Jul 15 11:52:13 2013 : Debug: require_strong = no<br>Mon Jul 15 11:52:13 2013 : Debug: with_ntdomain_hack = no<br>
Mon Jul 15 11:52:13 2013 : Debug: allow_retry = yes<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_digest, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_digest<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "digest" from file /etc/freeradius/modules/digest<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_unix, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_unix<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "unix" from file /etc/freeradius/modules/unix<br>Mon Jul 15 11:52:13 2013 : Debug: unix {<br>Mon Jul 15 11:52:13 2013 : Debug: radwtmp = "/var/log/freeradius/radwtmp"<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_eap, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_eap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "eap" from file /etc/freeradius/eap.conf<br>
Mon Jul 15 11:52:13 2013 : Debug: eap {<br>Mon Jul 15 11:52:13 2013 : Debug: default_eap_type = "peap"<br>Mon Jul 15 11:52:13 2013 : Debug: timer_expire = 60<br>Mon Jul 15 11:52:13 2013 : Debug: ignore_unknown_eap_types = no<br>
Mon Jul 15 11:52:13 2013 : Debug: cisco_accounting_username_bug = no<br>Mon Jul 15 11:52:13 2013 : Debug: max_sessions = 4096<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to sub-module rlm_eap_md5<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating eap-md5<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to sub-module rlm_eap_leap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating eap-leap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to sub-module rlm_eap_gtc<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating eap-gtc<br>Mon Jul 15 11:52:13 2013 : Debug: gtc {<br>Mon Jul 15 11:52:13 2013 : Debug: challenge = "Password: "<br>Mon Jul 15 11:52:13 2013 : Debug: auth_type = "PAP"<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to sub-module rlm_eap_tls<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating eap-tls<br>Mon Jul 15 11:52:13 2013 : Debug: tls {<br>
Mon Jul 15 11:52:13 2013 : Debug: rsa_key_exchange = no<br>Mon Jul 15 11:52:13 2013 : Debug: dh_key_exchange = yes<br>Mon Jul 15 11:52:13 2013 : Debug: rsa_key_length = 512<br>Mon Jul 15 11:52:13 2013 : Debug: dh_key_length = 512<br>
Mon Jul 15 11:52:13 2013 : Debug: verify_depth = 0<br>Mon Jul 15 11:52:13 2013 : Debug: CA_path = "/etc/freeradius/certs"<br>Mon Jul 15 11:52:13 2013 : Debug: pem_file_type = yes<br>Mon Jul 15 11:52:13 2013 : Debug: private_key_file = "/etc/freeradius/certs/server.pem"<br>
Mon Jul 15 11:52:13 2013 : Debug: certificate_file = "/etc/freeradius/certs/server.pem"<br>Mon Jul 15 11:52:13 2013 : Debug: CA_file = "/etc/freeradius/certs/ca.pem"<br>Mon Jul 15 11:52:13 2013 : Debug: private_key_password = "whatever"<br>
Mon Jul 15 11:52:13 2013 : Debug: dh_file = "/etc/freeradius/certs/dh"<br>Mon Jul 15 11:52:13 2013 : Debug: random_file = "/etc/freeradius/certs/random"<br>Mon Jul 15 11:52:13 2013 : Debug: fragment_size = 1024<br>
Mon Jul 15 11:52:13 2013 : Debug: include_length = yes<br>Mon Jul 15 11:52:13 2013 : Debug: check_crl = no<br>Mon Jul 15 11:52:13 2013 : Debug: cipher_list = "DEFAULT"<br>Mon Jul 15 11:52:13 2013 : Debug: ecdh_curve = "prime256v1"<br>
Mon Jul 15 11:52:13 2013 : Debug: cache {<br>Mon Jul 15 11:52:13 2013 : Debug: enable = no<br>Mon Jul 15 11:52:13 2013 : Debug: lifetime = 24<br>Mon Jul 15 11:52:13 2013 : Debug: max_entries = 255<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: verify {<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: ocsp {<br>Mon Jul 15 11:52:13 2013 : Debug: enable = no<br>Mon Jul 15 11:52:13 2013 : Debug: override_cert_url = yes<br>
Mon Jul 15 11:52:13 2013 : Debug: url = "<a href="http://127.0.0.1/ocsp/">http://127.0.0.1/ocsp/</a>"<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to sub-module rlm_eap_ttls<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating eap-ttls<br>Mon Jul 15 11:52:13 2013 : Debug: ttls {<br>Mon Jul 15 11:52:13 2013 : Debug: default_eap_type = "md5"<br>Mon Jul 15 11:52:13 2013 : Debug: copy_request_to_tunnel = no<br>
Mon Jul 15 11:52:13 2013 : Debug: use_tunneled_reply = no<br>Mon Jul 15 11:52:13 2013 : Debug: virtual_server = "inner-tunnel"<br>Mon Jul 15 11:52:13 2013 : Debug: include_length = yes<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to sub-module rlm_eap_peap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating eap-peap<br>Mon Jul 15 11:52:13 2013 : Debug: peap {<br>Mon Jul 15 11:52:13 2013 : Debug: default_eap_type = "mschapv2"<br>
Mon Jul 15 11:52:13 2013 : Debug: copy_request_to_tunnel = no<br>Mon Jul 15 11:52:13 2013 : Debug: use_tunneled_reply = no<br>Mon Jul 15 11:52:13 2013 : Debug: proxy_tunneled_request_as_eap = yes<br>Mon Jul 15 11:52:13 2013 : Debug: virtual_server = "inner-tunnel"<br>
Mon Jul 15 11:52:13 2013 : Debug: soh = no<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to sub-module rlm_eap_mschapv2<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating eap-mschapv2<br>
Mon Jul 15 11:52:13 2013 : Debug: mschapv2 {<br>Mon Jul 15 11:52:13 2013 : Debug: with_ntdomain_hack = no<br>Mon Jul 15 11:52:13 2013 : Debug: send_error = no<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking authorize {...} for more modules to load<br>
Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_preprocess, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_preprocess<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "preprocess" from file /etc/freeradius/modules/preprocess<br>
Mon Jul 15 11:52:13 2013 : Debug: preprocess {<br>Mon Jul 15 11:52:13 2013 : Debug: huntgroups = "/etc/freeradius/huntgroups"<br>Mon Jul 15 11:52:13 2013 : Debug: hints = "/etc/freeradius/hints"<br>
Mon Jul 15 11:52:13 2013 : Debug: with_ascend_hack = no<br>Mon Jul 15 11:52:13 2013 : Debug: ascend_channels_per_line = 23<br>Mon Jul 15 11:52:13 2013 : Debug: with_ntdomain_hack = no<br>Mon Jul 15 11:52:13 2013 : Debug: with_specialix_jetstream_hack = no<br>
Mon Jul 15 11:52:13 2013 : Debug: with_cisco_vsa_hack = no<br>Mon Jul 15 11:52:13 2013 : Debug: with_alvarion_vsa_hack = no<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_realm, checking if it's valid)<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_realm<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "suffix" from file /etc/freeradius/modules/realm<br>Mon Jul 15 11:52:13 2013 : Debug: realm suffix {<br>
Mon Jul 15 11:52:13 2013 : Debug: format = "suffix"<br>Mon Jul 15 11:52:13 2013 : Debug: delimiter = "@"<br>Mon Jul 15 11:52:13 2013 : Debug: ignore_default = no<br>Mon Jul 15 11:52:13 2013 : Debug: ignore_null = no<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_files, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_files<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "files" from file /etc/freeradius/modules/files<br>
Mon Jul 15 11:52:13 2013 : Debug: files {<br>Mon Jul 15 11:52:13 2013 : Debug: usersfile = "/etc/freeradius/users"<br>Mon Jul 15 11:52:13 2013 : Debug: acctusersfile = "/etc/freeradius/acct_users"<br>
Mon Jul 15 11:52:13 2013 : Debug: preproxy_usersfile = "/etc/freeradius/preproxy_users"<br>Mon Jul 15 11:52:13 2013 : Debug: compat = "no"<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking preacct {...} for more modules to load<br>
Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_acct_unique, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_acct_unique<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "acct_unique" from file /etc/freeradius/modules/acct_unique<br>
Mon Jul 15 11:52:13 2013 : Debug: acct_unique {<br>Mon Jul 15 11:52:13 2013 : Debug: key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Checking accounting {...} for more modules to load<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_detail, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_detail<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "detail" from file /etc/freeradius/modules/detail<br>Mon Jul 15 11:52:13 2013 : Debug: detail {<br>Mon Jul 15 11:52:13 2013 : Debug: detailfile = "/var/log/freeradius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"<br>
Mon Jul 15 11:52:13 2013 : Debug: header = "%t"<br>Mon Jul 15 11:52:13 2013 : Debug: detailperm = 384<br>Mon Jul 15 11:52:13 2013 : Debug: dirperm = 493<br>Mon Jul 15 11:52:13 2013 : Debug: locking = no<br>
Mon Jul 15 11:52:13 2013 : Debug: log_packet_header = no<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_radutmp, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_radutmp<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "radutmp" from file /etc/freeradius/modules/radutmp<br>Mon Jul 15 11:52:13 2013 : Debug: radutmp {<br>Mon Jul 15 11:52:13 2013 : Debug: filename = "/var/log/freeradius/radutmp"<br>
Mon Jul 15 11:52:13 2013 : Debug: username = "%{User-Name}"<br>Mon Jul 15 11:52:13 2013 : Debug: case_sensitive = yes<br>Mon Jul 15 11:52:13 2013 : Debug: check_with_nas = yes<br>Mon Jul 15 11:52:13 2013 : Debug: perm = 384<br>
Mon Jul 15 11:52:13 2013 : Debug: callerid = yes<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_attr_filter, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_attr_filter<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "attr_filter.accounting_response" from file /etc/freeradius/modules/attr_filter<br>Mon Jul 15 11:52:13 2013 : Debug: attr_filter attr_filter.accounting_response {<br>
Mon Jul 15 11:52:13 2013 : Debug: attrsfile = "/etc/freeradius/attrs.accounting_response"<br>Mon Jul 15 11:52:13 2013 : Debug: key = "%{User-Name}"<br>Mon Jul 15 11:52:13 2013 : Debug: relaxed = no<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking session {...} for more modules to load<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking post-proxy {...} for more modules to load<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Checking post-auth {...} for more modules to load<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "attr_filter.access_reject" from file /etc/freeradius/modules/attr_filter<br>
Mon Jul 15 11:52:13 2013 : Debug: attr_filter attr_filter.access_reject {<br>Mon Jul 15 11:52:13 2013 : Debug: attrsfile = "/etc/freeradius/attrs.access_reject"<br>Mon Jul 15 11:52:13 2013 : Debug: key = "%{User-Name}"<br>
Mon Jul 15 11:52:13 2013 : Debug: relaxed = no<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: } # modules<br>Mon Jul 15 11:52:13 2013 : Debug: } # server<br>Mon Jul 15 11:52:13 2013 : Debug: server inner-tunnel { # from file /etc/freeradius/sites-enabled/inner-tunnel<br>
Mon Jul 15 11:52:13 2013 : Debug: modules {<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking authenticate {...} for more modules to load<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking authorize {...} for more modules to load<br>
Mon Jul 15 11:52:13 2013 : Debug: (Loaded rlm_ldap, checking if it's valid)<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Linked to module rlm_ldap<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Instantiating module "ldap" from file /etc/freeradius/modules/ldap<br>
Mon Jul 15 11:52:13 2013 : Debug: ldap {<br>Mon Jul 15 11:52:13 2013 : Debug: server = "<a href="http://ldap.test.example.com">ldap.test.example.com</a>"<br>Mon Jul 15 11:52:13 2013 : Debug: port = 389<br>
Mon Jul 15 11:52:13 2013 : Debug: password = "secret"<br>Mon Jul 15 11:52:13 2013 : Debug: identity = "cn=admin,dc=example,dc=com"<br>Mon Jul 15 11:52:13 2013 : Debug: net_timeout = 1<br>Mon Jul 15 11:52:13 2013 : Debug: timeout = 4<br>
Mon Jul 15 11:52:13 2013 : Debug: timelimit = 3<br>Mon Jul 15 11:52:13 2013 : Debug: tls_mode = no<br>Mon Jul 15 11:52:13 2013 : Debug: start_tls = no<br>Mon Jul 15 11:52:13 2013 : Debug: tls_require_cert = "allow"<br>
Mon Jul 15 11:52:13 2013 : Debug: tls {<br>Mon Jul 15 11:52:13 2013 : Debug: start_tls = no<br>Mon Jul 15 11:52:13 2013 : Debug: require_cert = "allow"<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: basedn = "dc=example,dc=com"<br>
Mon Jul 15 11:52:13 2013 : Debug: filter = "(uid=%{%{Stripped-User-Name}:-%{User-Name}})"<br>Mon Jul 15 11:52:13 2013 : Debug: base_filter = "(objectclass=radiusprofile)"<br>Mon Jul 15 11:52:13 2013 : Debug: password_attribute = "userPassword"<br>
Mon Jul 15 11:52:13 2013 : Debug: auto_header = no<br>Mon Jul 15 11:52:13 2013 : Debug: access_attr_used_for_allow = yes<br>Mon Jul 15 11:52:13 2013 : Debug: groupname_attribute = "cn"<br>Mon Jul 15 11:52:13 2013 : Debug: groupmembership_filter = "(|(&(objectClass=GroupOfNames)(member=%{Ldap-UserDn}))(&(objectClass=GroupOfUniqueNames)(uniquemember=%{Ldap-UserDn})))"<br>
Mon Jul 15 11:52:13 2013 : Debug: dictionary_mapping = "/etc/freeradius/ldap.attrmap"<br>Mon Jul 15 11:52:13 2013 : Debug: ldap_debug = 0<br>Mon Jul 15 11:52:13 2013 : Debug: ldap_connections_number = 5<br>
Mon Jul 15 11:52:13 2013 : Debug: compare_check_items = no<br>Mon Jul 15 11:52:13 2013 : Debug: do_xlat = yes<br>Mon Jul 15 11:52:13 2013 : Debug: edir_account_policy_check = no<br>Mon Jul 15 11:52:13 2013 : Debug: set_auth_type = yes<br>
Mon Jul 15 11:52:13 2013 : Debug: keepalive {<br>Mon Jul 15 11:52:13 2013 : Debug: idle = 60<br>Mon Jul 15 11:52:13 2013 : Debug: probes = 3<br>Mon Jul 15 11:52:13 2013 : Debug: interval = 3<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: Registering ldap_groupcmp for Ldap-Group<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: Registering ldap_xlat with xlat_name ldap<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: Over-riding set_auth_type, as there is no module ldap listed in the "authenticate" section.<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: reading ldap<->radius mappings from file /etc/freeradius/ldap.attrmap<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP lmPassword mapped to RADIUS LM-Password<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP ntPassword mapped to RADIUS NT-Password<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP sambaLmPassword mapped to RADIUS LM-Password<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP sambaNtPassword mapped to RADIUS NT-Password<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP dBCSPwd mapped to RADIUS LM-Password<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP userPassword mapped to RADIUS Password-With-Header<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusNASIpAddress mapped to RADIUS NAS-IP-Address<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusLoginIPHost mapped to RADIUS Login-IP-Host<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusClass mapped to RADIUS Class<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS Framed-AppleTalk-Link<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS Framed-AppleTalk-Network<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS Framed-AppleTalk-Zone<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusReplyMessage mapped to RADIUS Reply-Message<br>
Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusTunnelType mapped to RADIUS Tunnel-Type<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusTunnelMediumType mapped to RADIUS Tunnel-Medium-Type<br>Mon Jul 15 11:52:13 2013 : Debug: rlm_ldap: LDAP radiusTunnelPrivateGroupId mapped to RADIUS Tunnel-Private-Group-Id<br>
Mon Jul 15 11:52:13 2013 : Debug: conns: 0x222e460<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking session {...} for more modules to load<br>Mon Jul 15 11:52:13 2013 : Debug: Module: Checking post-proxy {...} for more modules to load<br>
Mon Jul 15 11:52:13 2013 : Debug: Module: Checking post-auth {...} for more modules to load<br>Mon Jul 15 11:52:13 2013 : Debug: } # modules<br>Mon Jul 15 11:52:13 2013 : Debug: } # server<br>Mon Jul 15 11:52:13 2013 : Debug: radiusd: #### Opening IP addresses and Ports ####<br>
Mon Jul 15 11:52:13 2013 : Debug: listen {<br>Mon Jul 15 11:52:13 2013 : Debug: type = "auth"<br>Mon Jul 15 11:52:13 2013 : Debug: ipaddr = *<br>Mon Jul 15 11:52:13 2013 : Debug: port = 0<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: listen {<br>Mon Jul 15 11:52:13 2013 : Debug: type = "acct"<br>Mon Jul 15 11:52:13 2013 : Debug: ipaddr = *<br>Mon Jul 15 11:52:13 2013 : Debug: port = 0<br>Mon Jul 15 11:52:13 2013 : Debug: }<br>
Mon Jul 15 11:52:13 2013 : Debug: listen {<br>Mon Jul 15 11:52:13 2013 : Debug: type = "auth"<br>Mon Jul 15 11:52:13 2013 : Debug: ipaddr = 127.0.0.1<br>Mon Jul 15 11:52:13 2013 : Debug: port = 18120<br>
Mon Jul 15 11:52:13 2013 : Debug: }<br>Mon Jul 15 11:52:13 2013 : Info: ... adding new socket proxy address * port 44220<br>Mon Jul 15 11:52:13 2013 : Debug: Listening on authentication address * port 1812<br>Mon Jul 15 11:52:13 2013 : Debug: Listening on accounting address * port 1813<br>
Mon Jul 15 11:52:13 2013 : Debug: Listening on authentication address 127.0.0.1 port 18120 as server inner-tunnel<br>Mon Jul 15 11:52:13 2013 : Debug: Listening on proxy address * port 1814<br>Mon Jul 15 11:52:13 2013 : Info: Ready to process requests.<br>
rad_recv: Access-Request packet from host 127.0.0.1 port 51007, id=10, length=142<br> User-Name = "testuser"<br> NAS-IP-Address = 127.0.1.1<br> NAS-Port = 0<br> Message-Authenticator = 0x501b45980cbac2bf712e39b9d39dcbc4<br>
Framed-Protocol = PPP<br> MS-CHAP-Challenge = 0x6f2e8054de0d1c67<br> MS-CHAP-Response = 0x0001000000000000000000000000000000000000000000000000d4852e3f509ae7fb76b929072f32e2cfe2d5b2cc1ef4ffaf<br>Mon Jul 15 11:52:27 2013 : Info: server inner-tunnel {<br>
Mon Jul 15 11:52:27 2013 : Info: # Executing section authorize from file /etc/freeradius/sites-enabled/inner-tunnel<br>Mon Jul 15 11:52:27 2013 : Info: +- entering group authorize {...}<br>Mon Jul 15 11:52:27 2013 : Info: ++[chap] returns noop<br>
Mon Jul 15 11:52:27 2013 : Info: [mschap] Found MS-CHAP attributes. Setting 'Auth-Type = mschap'<br>Mon Jul 15 11:52:27 2013 : Info: ++[mschap] returns ok<br>Mon Jul 15 11:52:27 2013 : Info: [suffix] No '@' in User-Name = "testuser", looking up realm NULL<br>
Mon Jul 15 11:52:27 2013 : Info: [suffix] No such realm "NULL"<br>Mon Jul 15 11:52:27 2013 : Info: ++[suffix] returns noop<br>Mon Jul 15 11:52:27 2013 : Info: ++[control] returns noop<br>Mon Jul 15 11:52:27 2013 : Info: [eap] No EAP-Message, not doing EAP<br>
Mon Jul 15 11:52:27 2013 : Info: ++[eap] returns noop<br>Mon Jul 15 11:52:27 2013 : Info: [files] users: Matched entry DEFAULT at line 174<br>Mon Jul 15 11:52:27 2013 : Info: ++[files] returns ok<br>Mon Jul 15 11:52:27 2013 : Info: [ldap] performing user authorization for testuser<br>
Mon Jul 15 11:52:27 2013 : Info: [ldap] expand: %{Stripped-User-Name} -> <br>Mon Jul 15 11:52:27 2013 : Info: [ldap] ... expanding second conditional<br>Mon Jul 15 11:52:27 2013 : Info: [ldap] expand: %{User-Name} -> testuser<br>
Mon Jul 15 11:52:27 2013 : Info: [ldap] expand: (uid=%{%{Stripped-User-Name}:-%{User-Name}}) -> (uid=testuser)<br>Mon Jul 15 11:52:27 2013 : Info: [ldap] expand: dc=example,dc=com -> dc=example,dc=com<br>Mon Jul 15 11:52:27 2013 : Debug: [ldap] ldap_get_conn: Checking Id: 0<br>
Mon Jul 15 11:52:27 2013 : Debug: [ldap] ldap_get_conn: Got Id: 0<br>Mon Jul 15 11:52:27 2013 : Debug: [ldap] attempting LDAP reconnection<br>Mon Jul 15 11:52:27 2013 : Debug: [ldap] (re)connect to <a href="http://ldap.test.example.com:389">ldap.test.example.com:389</a>, authentication 0<br>
Mon Jul 15 11:52:27 2013 : Debug: [ldap] bind as cn=admin,dc=example,dc=com/010203 to <a href="http://ldap.test.example.com:389">ldap.test.example.com:389</a><br>Mon Jul 15 11:52:27 2013 : Debug: [ldap] waiting for bind result ...<br>
Mon Jul 15 11:52:27 2013 : Debug: [ldap] Bind was successful<br>Mon Jul 15 11:52:27 2013 : Debug: [ldap] performing search in dc=example,dc=com, with filter (uid=testuser)<br>Mon Jul 15 11:52:27 2013 : Info: [ldap] Added User-Password = {SSHA}xxxxxxxxxxxxxxxxxxxxxxxxxx in check items<br>
Mon Jul 15 11:52:27 2013 : Info: [ldap] No default NMAS login sequence<br>Mon Jul 15 11:52:27 2013 : Info: [ldap] looking for check items in directory...<br>Mon Jul 15 11:52:27 2013 : Debug: [ldap] userPassword -> Password-With-Header == "{SSHA}xxxxxxxxxxxxxxxx"<br>
Mon Jul 15 11:52:27 2013 : Debug: [ldap] sambaNtPassword -> NT-Password == 0xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx<br>Mon Jul 15 11:52:27 2013 : Info: [ldap] looking for reply items in directory...<br>Mon Jul 15 11:52:27 2013 : Info: [ldap] user testuser authorized to use remote access<br>
Mon Jul 15 11:52:27 2013 : Debug: [ldap] ldap_release_conn: Release Id: 0<br>Mon Jul 15 11:52:27 2013 : Info: ++[ldap] returns ok<br>Mon Jul 15 11:52:27 2013 : Info: ++[expiration] returns noop<br>Mon Jul 15 11:52:27 2013 : Info: ++[logintime] returns noop<br>
Mon Jul 15 11:52:27 2013 : Info: [pap] Normalizing NT-Password from hex encoding<br>Mon Jul 15 11:52:27 2013 : Info: [pap] Normalizing SSHA1-Password from base64 encoding<br>Mon Jul 15 11:52:27 2013 : Info: [pap] Normalizing SSHA1-Password from base64 encoding<br>
Mon Jul 15 11:52:27 2013 : Info: [pap] WARNING: Auth-Type already set. Not setting to PAP<br>Mon Jul 15 11:52:27 2013 : Info: ++[pap] returns noop<br>Mon Jul 15 11:52:27 2013 : Info: Found Auth-Type = MSCHAP<br>Mon Jul 15 11:52:27 2013 : Info: # Executing group from file /etc/freeradius/sites-enabled/inner-tunnel<br>
Mon Jul 15 11:52:27 2013 : Info: +- entering group MS-CHAP {...}<br>Mon Jul 15 11:52:27 2013 : Info: [mschap] No Cleartext-Password configured. Cannot create LM-Password.<br>Mon Jul 15 11:52:27 2013 : Info: [mschap] Found NT-Password<br>
Mon Jul 15 11:52:27 2013 : Info: [mschap] Told to do MS-CHAPv1 with NT-Password<br>Mon Jul 15 11:52:27 2013 : Info: [mschap] MS-CHAP-Response is incorrect.<br>Mon Jul 15 11:52:27 2013 : Info: ++[mschap] returns reject<br>
Mon Jul 15 11:52:27 2013 : Info: Failed to authenticate the user.<br>Mon Jul 15 11:52:27 2013 : Info: } # server inner-tunnel<br>Mon Jul 15 11:52:27 2013 : Info: Using Post-Auth-Type Reject<br>Mon Jul 15 11:52:27 2013 : Info: # Executing group from file /etc/freeradius/sites-enabled/inner-tunnel<br>
Mon Jul 15 11:52:27 2013 : Info: +- entering group REJECT {...}<br>Mon Jul 15 11:52:27 2013 : Info: [attr_filter.access_reject] expand: %{User-Name} -> testuser<br>Mon Jul 15 11:52:27 2013 : Debug: attr_filter: Matched entry DEFAULT at line 11<br>
Mon Jul 15 11:52:27 2013 : Info: ++[attr_filter.access_reject] returns updated<br>Mon Jul 15 11:52:27 2013 : Info: Delaying reject of request 0 for 1 seconds<br>Mon Jul 15 11:52:27 2013 : Debug: Going to the next request<br>
Mon Jul 15 11:52:27 2013 : Debug: Waking up in 0.9 seconds.<br>Mon Jul 15 11:52:28 2013 : Info: Sending delayed reject for request 0<br>Sending Access-Reject of id 10 to 127.0.0.1 port 51007<br> MS-CHAP-Error = "\000E=691 R=1"<br>
Mon Jul 15 11:52:28 2013 : Debug: Waking up in 4.9 seconds.<br></snip><br></div></div>