Freeradius-Users
Threads by month
- ----- 2026 -----
- July
- June
- May
- April
- March
- February
- January
- ----- 2025 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2024 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2023 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2022 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2021 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2020 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2019 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2018 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2017 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2016 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2015 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2014 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2013 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2012 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2011 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2010 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2009 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2008 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2007 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2006 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2005 -----
- December
- November
- October
- September
- August
- July
- June
- May
- 10 participants
- 27050 discussions
I want to set up my FreeRADIUS server to check the supplied user name and
decide which DC to use for authentication. I have tried using the following
code in my authorize section of a custom sites-enabled file.
# Check if the User-Name contains an email
if ("%{User-Name}" =~ /(a)(.+)$/) {
# Extract domain from email and store in email_domain
set email_domain = "%{1}"
# Conditionally set dc based on the domain
if ("%{email_domain}" == "student.madeupdomain.com") {
update control {
LDAP-Base-DN := "dc=student,dc=madeupdomain,dc=com"
}
}
else {
update control {
LDAP-Base-DN := "dc=madeupdomain,dc=com"
}
}
}
With this code in the custom file, the server gives an error message of
"Expecting section start brace '{' after "set email_domain". I have tried
adding a start brace before the if statement to assign the DC to use, but
that has made no difference. To check, I tried ChatGPT and put this code
in. ChatGPT says the code is correct.
Wayne Sprouse
--
"The
foregoing electronic message and any files transmitted with it are
confidential and are intended only for the use of the intended recipient
named above. This communication may contain material protected by the
Family Educational Rights and Privacy Act (FERPA). If you are not the
intended recipient, copying, distribution or use of the contents of this
message is strictly prohibited. If you received this electronic
message
in error, please notify us immediately at (228-864-1146**)."**
2
5
Hi,
Sometimes occures "WARNING: Module rlm_sql became unblocked" in the
"Received Accounting-Request" on the FR server logs and dont sent back
the "Sent Accounting-Response" packet to the NAS. But when I checked the
db. radacct records, the acct. "Interim-Update" record was inserted into
the radacct table.
But NAS sends the "Accounting-Request" packet again and again a
duplicate error is logged because it has been entered into the db before.
I don't observe any slowdown in db. and server load is also quite low.
This creates unnecessary acct. traffic and causes radius server UP-DOWN
on the NAS side then switch into secondary FR server.
How can I solve this problem?
FreeRADIUS Version 3.0.20 and db. PostgreSQL 12.8, db. server and radius
server are on separate servers.
Regards
--
Can Paçacı
2
1
Re: [External Mail] Re: [External Mail] [External Mail] Issue with authentication for certain users
by Wayne Sprouse 07 Apr '25
by Wayne Sprouse 07 Apr '25
07 Apr '25
I have looked up documentation on configuring FreeRADIUS to
authenticate using the users' mail addresses. I have everything set up
correctly from looking at the documentation, I have the filter set to
mail={%User-Name}. The FreeRADIUS server looks for the user by the
email address when looking at the Google LDAP logs. Google's LDAP
server responds with the UID of the user. Then, the FreeRADIUS server
attempts to log in using the UID. I do not want this as this is
causing all the users in our subdomain to fail the bind. This is
because for the subdomain, it would need to be
dc=student,dc=madeupdomain,dc=com and that is not how Google responded
for the UID of the user. Do I have something configured wrong or is
there a way to force the mail address only?
Wayne Sprouse, CNA,CCNA,CEH,A+
Wayne Sprouse, CNA,CCNA,CEH,A+
Long Beach School District
Network Administrator
Work Phone: 228-575-4056
On Thu, Apr 3, 2025 at 1:38 PM Wayne Sprouse <wayne.sprouse(a)lbsdk12.com> wrote:
>
> > The point is to tell Google that you were using ldapsearch.
>
> My mistake, I didn't fully understand that. I have told Google about
> doing the ldapsearch and not being able to bind with the subdomain
> credentials.
>
> > Yes, you can configure the LDAP module to search using the User-Name. This is the default configuration.
>
> It is currently set to search using the filter of their email address.
> It then gets back the full UID DN from the Google LDAP. I was wanting
> it to not bother with the UID and instead use the email address as the
> complete User-Name to authenticate into LDAP.
>
>
> Wayne Sprouse, CNA,CCNA,CEH,A+
--
"The
foregoing electronic message and any files transmitted with it are
confidential and are intended only for the use of the intended recipient
named above. This communication may contain material protected by the
Family Educational Rights and Privacy Act (FERPA). If you are not the
intended recipient, copying, distribution or use of the contents of this
message is strictly prohibited. If you received this electronic
message
in error, please notify us immediately at (228-864-1146**)."**
2
1
1
0
Not sure what to do about this so I'm not proposing any solution. Just
wanted to describe the problem for future reference. Any maybe someone
smarter knows how to "fix" it. WHich is mostly about helping users
DTRT.
Our FR config does stupid(?) stuff like converting binary attributes to
strings and then saving the result to varchar fields in a mysql
database. The assumption is that the binary attribute (the actual
example is ADSL-Agent-Circuit-Id) really contains ascii strings. Which
it does most of the time. There are of course some exceptions with an
assorted set of L2 devices on all the different access technoligies you
can think of. These are escaped as quoted-printable chars by FR so we
don't need to care.
For historical reasons, the mysql table we write this field to has
DEFAULT CHARSET=latin1
After an OS upgrade (to RHEL9, FWIW) I ended up with a mysql client
library defaulting to utf8 connections. Should not be a problem since
mysql handles the translation. But then I hit some of those binary
sequencence which FreeRADIUS happend to validate as multibyte utf8, and
which therefore was NOT escaped.
However, mysql didn't know how to translate them into latin1 and bailed
out with:
rlm_sql_mysql: ERROR 1366 (Incorrect string value: '\xDF\xA4k=5C...' for column 'ADSLAgentCircuitId' at row 1): HY000
I know I shot myself in the foot. There are several workarounds for this
problem, and I have implemented one that works for me as suggested in
the default configuration files:
Create a my.cnf file with
[freeradius]
default-character-set = latin1
and set MYSQL_HOME to the directory with this file before starting FR.
But the reason I am posting this is that the sql_escape_func() in
rlm_sql suprised me, and I'm not sure the logic there makes sense? By
default it escapes any character outside the "safe_characters" set:
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
Except that it doesn't touch any byte sequence which validates as
multi-byte utf8:
/*
* Allow all multi-byte UTF8 characters.
*/
utf8_len = fr_utf8_char((uint8_t const *) in, -1);
if (utf8_len > 1) {
if (outlen <= utf8_len) break;
memcpy(out, in, utf8_len);
in += utf8_len;
out += utf8_len;
outlen -= utf8_len;
len += utf8_len;
continue;
}
And fr_utf8_char() implements
https://datatracker.ietf.org/doc/html/rfc2279#section-2
correctly as far as I can see. Meaning that for example any
two byte sequence matching the bit pattern
"110xxxxx 10xxxxxx"
is accepted as utf8 and therefor not escaped. My example above
'\xDF\xA4' matches this pattern. So the lack of escaping is expected.
This works as long as client and table use the same character set. But
as shown, it fails when there is a mismatch.
The failure can be easily demonstrated with a command line client using
a utf8 connection:
root(a)127.0.0.1 (foo)> \s
--------------
mysql Ver 8.0.41 for Linux on x86_64 (MySQL Community Server - GPL)
Connection id: 12610
Current database: foo
Current user: root@localhost
SSL: Cipher in use is TLS_AES_256_GCM_SHA384
Current pager: less
Using outfile: ''
Using delimiter: ;
Server version: 8.0.41 MySQL Community Server - GPL
Protocol version: 10
Connection: 127.0.0.1 via TCP/IP
Server characterset: utf8mb4
Db characterset: utf8mb4
Client characterset: utf8mb4
Conn. characterset: utf8mb4
TCP port: 15042
Binary data as: Hexadecimal
Uptime: 8 days 23 hours 17 min 24 sec
Threads: 12 Questions: 160166 Slow queries: 0 Opens: 275 Flush tables: 3 Open tables: 192 Queries per second avg: 0.206
--------------
Using two identical tables differing only in default charset:
root(a)127.0.0.1 (foo)> show create table bar\G
*************************** 1. row ***************************
Table: bar
Create Table: CREATE TABLE `bar` (
`baz` varchar(64) DEFAULT NULL
) ENGINE=InnoDB DEFAULT CHARSET=latin1
1 row in set (0.00 sec)
root(a)127.0.0.1 (foo)> show create table bar2\G
*************************** 1. row ***************************
Table: bar2
Create Table: CREATE TABLE `bar2` (
`baz` varchar(64) DEFAULT NULL
) ENGINE=InnoDB DEFAULT CHARSET=utf8mb4 COLLATE=utf8mb4_0900_ai_ci
1 row in set (0.00 sec)
We can insert \uDFA4 into the second table, but not into the first one:
root(a)127.0.0.1 (foo)> insert into bar values ('ߤ');
ERROR 1366 (HY000): Incorrect string value: '\xDF\xA4' for column 'baz' at row 1
root(a)127.0.0.1 (foo)> insert into bar2 values ('ߤ');
Query OK, 1 row affected (0.00 sec)
I have several questions after hitting this hard:
Should FR handle the situation better, detecting the character set
mismatches and automatically escape utf8 multi-byte sequences when the
target table use a different charset?
Should there be a way to configure rlm_sql into always escaping utf8
multi-byte sequences?
Does the default aggressive ascii escaping really align with allowing
any multi-byte utf8? The end results look strange and unexpected IMHO.
Any thoughts outside "don't to that then"?
Bjørn
2
3
Hi,
Please can you advise why the connection is not succeeding?
The Problem:
A Windows client is not succeeding authentication and the debug does
not show that the TLS handshake completes over WAN; the server is
hosted in Azure Container Instance. I have tested the same container
locally on the LAN which is working OK (docker container running on a
VM with a separate laptop with 802.1x configured with a switch set as
the NAC).
I can see the TLS handshake when comparing to a working example seems
to be missing the below:
(6) &session-state:TLS-Session-Cipher-Suite = "TLS_AES_256_GCM_SHA384"
(6) &session-state:TLS-Session-Version = "TLS 1.3"
(6) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Finished"
What you are trying to do: Authenticate a Windows client via 802.1X
with EAP-TLS over the internet to an Azure Container Instance running
freeRADIUS.
This is behind a load balancer with a public IP, which includes a
health check on port 8080. This works when using eapol_test but not
when using Windows authentication. Although, this works for both
Windows and eapol_test when running the container on the local
network.
why you are trying to do it: Understand why this fails the TLS
handshake over the internet.
what you expect the server to do: TLS handshake to complete and
authentication request to receive an Access-Accept
what the server does instead (i.e. debug output):
FreeRADIUS Version 3.2.7
Copyright (C) 1999-2023 The FreeRADIUS server project and contributors
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License
For more information about these matters, see the file named COPYRIGHT
Starting - reading configuration files ...
including dictionary file /usr/share/freeradius/dictionary
including dictionary file /usr/share/freeradius/dictionary.dhcp
including dictionary file /usr/share/freeradius/dictionary.vqp
including dictionary file /etc/freeradius/dictionary
including configuration file /etc/freeradius/radiusd.conf
including configuration file /etc/freeradius/proxy.conf
including configuration file /etc/freeradius/clients.conf
including files in directory /etc/freeradius/mods-enabled/
including configuration file /etc/freeradius/mods-enabled/always
including configuration file /etc/freeradius/mods-enabled/attr_filter
including configuration file /etc/freeradius/mods-enabled/chap
including configuration file /etc/freeradius/mods-enabled/date
including configuration file /etc/freeradius/mods-enabled/detail
including configuration file /etc/freeradius/mods-enabled/detail.log
including configuration file /etc/freeradius/mods-enabled/digest
including configuration file /etc/freeradius/mods-enabled/dynamic_clients
including configuration file /etc/freeradius/mods-enabled/eap
including configuration file /etc/freeradius/mods-enabled/echo
including configuration file /etc/freeradius/mods-enabled/exec
including configuration file /etc/freeradius/mods-enabled/expiration
including configuration file /etc/freeradius/mods-enabled/expr
including configuration file /etc/freeradius/mods-enabled/files
including configuration file /etc/freeradius/mods-enabled/linelog
including configuration file /etc/freeradius/mods-enabled/logintime
including configuration file /etc/freeradius/mods-enabled/mschap
including configuration file /etc/freeradius/mods-enabled/ntlm_auth
including configuration file /etc/freeradius/mods-enabled/pap
including configuration file /etc/freeradius/mods-enabled/passwd
including configuration file /etc/freeradius/mods-enabled/preprocess
including configuration file /etc/freeradius/mods-enabled/proxy_rate_limit
including configuration file /etc/freeradius/mods-enabled/radutmp
including configuration file /etc/freeradius/mods-enabled/realm
including configuration file /etc/freeradius/mods-enabled/replicate
including configuration file /etc/freeradius/mods-enabled/soh
including configuration file /etc/freeradius/mods-enabled/sradutmp
including configuration file /etc/freeradius/mods-enabled/totp
including configuration file /etc/freeradius/mods-enabled/unix
including configuration file /etc/freeradius/mods-enabled/unpack
including configuration file /etc/freeradius/mods-enabled/utf8
including files in directory /etc/freeradius/policy.d/
including configuration file /etc/freeradius/policy.d/abfab-tr
including configuration file /etc/freeradius/policy.d/accounting
including configuration file /etc/freeradius/policy.d/canonicalization
including configuration file /etc/freeradius/policy.d/control
including configuration file /etc/freeradius/policy.d/cui
including configuration file /etc/freeradius/policy.d/debug
including configuration file /etc/freeradius/policy.d/dhcp
including configuration file /etc/freeradius/policy.d/eap
including configuration file /etc/freeradius/policy.d/filter
including configuration file /etc/freeradius/policy.d/moonshot-targeted-ids
including configuration file /etc/freeradius/policy.d/operator-name
including configuration file /etc/freeradius/policy.d/rfc7542
including files in directory /etc/freeradius/sites-enabled/
including configuration file /etc/freeradius/sites-enabled/default
including configuration file /etc/freeradius/sites-enabled/inner-tunnel
including configuration file /etc/freeradius/sites-enabled/check-eap-tls
including configuration file /etc/freeradius/sites-enabled/status
main {
security {
allow_core_dumps = no
}
name = "radiusd"
prefix = "@prefix@"
localstatedir = "@localstatedir@"
logdir = "@logdir@"
run_dir = "@localstatedir@/run/radiusd"
}
main {
name = "radiusd"
prefix = "@prefix@"
localstatedir = "@localstatedir@"
sbindir = "@sbindir@"
logdir = "@logdir@"
run_dir = "@localstatedir@/run/radiusd"
libdir = "@libdir@"
radacctdir = "@radacctdir@"
hostname_lookups = no
max_request_time = 30
proxy_dedup_window = 1
cleanup_delay = 30
max_requests = 16384
max_fds = 512
postauth_client_lost = no
pidfile = "@localstatedir@/run/radiusd/radiusd.pid"
checkrad = "@sbindir@/checkrad"
debug_level = 0
proxy_requests = yes
log {
stripped_names = no
auth = no
auth_badpass = no
auth_goodpass = no
colourise = yes
msg_denied = "You are already logged in - access denied"
}
resources {
}
security {
max_attributes = 200
reject_delay = 1.000000
status_server = yes
require_message_authenticator = "auto"
limit_proxy_state = "auto"
}
unlang {
group_stop_return = no
policy_stop_return = no
}
}
radiusd: #### Loading Realms and Home Servers ####
proxy server {
retry_delay = 5
retry_count = 3
default_fallback = no
dead_time = 120
wake_all_if_all_dead = no
}
home_server localhost {
nonblock = no
ipaddr = 127.0.0.1
port = 1812
type = "auth"
secret = <<< secret >>>
response_window = 20.000000
response_timeouts = 1
max_outstanding = 65536
zombie_period = 40
status_check = "status-server"
ping_interval = 30
check_interval = 30
check_timeout = 4
num_answers_to_alive = 3
revive_interval = 120
limit {
max_connections = 16
max_requests = 0
lifetime = 0
idle_timeout = 0
}
coa {
irt = 2
mrt = 16
mrc = 5
mrd = 30
}
}
home_server_pool my_auth_failover {
type = fail-over
home_server = localhost
}
realm example.com {
auth_pool = my_auth_failover
}
realm LOCAL {
}
radiusd: #### Loading Clients ####
client localhost {
ipaddr = 127.0.0.1
require_message_authenticator = "auto"
limit_proxy_state = "auto"
secret = <<< secret >>>
proto = "*"
limit {
max_connections = 16
lifetime = 0
idle_timeout = 30
}
}
Shared secret for client localhost is short, and likely can be broken
by an attacker.
client localhost_ipv6 {
ipv6addr = ::1
secret = <<< secret >>>
limit {
max_connections = 16
lifetime = 0
idle_timeout = 30
}
}
Shared secret for client localhost_ipv6 is short, and likely can be
broken by an attacker.
client radiustesting {
ipaddr = 185.241.224.187
secret = <<< secret >>>
limit {
max_connections = 16
lifetime = 0
idle_timeout = 30
}
}
Debug state unknown (cap_sys_ptrace capability not set)
Configuration version: 7161-5ad1-6c77-638f
systemd watchdog is disabled
# Creating Auth-Type = mschap
# Creating Auth-Type = digest
# Creating Auth-Type = eap
# Creating Auth-Type = PAP
# Creating Auth-Type = CHAP
# Creating Auth-Type = MS-CHAP
# Creating Autz-Type = New-TLS-Connection
# Creating Autz-Type = Status-Server
radiusd: #### Instantiating modules ####
modules {
# Loaded module rlm_always
# Loading module "reject" from file /etc/freeradius/mods-enabled/always
always reject {
rcode = "reject"
simulcount = 0
mpp = no
}
# Loading module "fail" from file /etc/freeradius/mods-enabled/always
always fail {
rcode = "fail"
simulcount = 0
mpp = no
}
# Loading module "ok" from file /etc/freeradius/mods-enabled/always
always ok {
rcode = "ok"
simulcount = 0
mpp = no
}
# Loading module "handled" from file /etc/freeradius/mods-enabled/always
always handled {
rcode = "handled"
simulcount = 0
mpp = no
}
# Loading module "invalid" from file /etc/freeradius/mods-enabled/always
always invalid {
rcode = "invalid"
simulcount = 0
mpp = no
}
# Loading module "userlock" from file /etc/freeradius/mods-enabled/always
always userlock {
rcode = "userlock"
simulcount = 0
mpp = no
}
# Loading module "notfound" from file /etc/freeradius/mods-enabled/always
always notfound {
rcode = "notfound"
simulcount = 0
mpp = no
}
# Loading module "noop" from file /etc/freeradius/mods-enabled/always
always noop {
rcode = "noop"
simulcount = 0
mpp = no
}
# Loading module "updated" from file /etc/freeradius/mods-enabled/always
always updated {
rcode = "updated"
simulcount = 0
mpp = no
}
# Loaded module rlm_attr_filter
# Loading module "attr_filter.post-proxy" from file
/etc/freeradius/mods-enabled/attr_filter
attr_filter attr_filter.post-proxy {
filename = "/etc/freeradius/mods-config/attr_filter/post-proxy"
key = "%{Realm}"
relaxed = no
}
# Loading module "attr_filter.pre-proxy" from file
/etc/freeradius/mods-enabled/attr_filter
attr_filter attr_filter.pre-proxy {
filename = "/etc/freeradius/mods-config/attr_filter/pre-proxy"
key = "%{Realm}"
relaxed = no
}
# Loading module "attr_filter.access_reject" from file
/etc/freeradius/mods-enabled/attr_filter
attr_filter attr_filter.access_reject {
filename = "/etc/freeradius/mods-config/attr_filter/access_reject"
key = "%{User-Name}"
relaxed = no
}
# Loading module "attr_filter.access_challenge" from file
/etc/freeradius/mods-enabled/attr_filter
attr_filter attr_filter.access_challenge {
filename = "/etc/freeradius/mods-config/attr_filter/access_challenge"
key = "%{User-Name}"
relaxed = no
}
# Loading module "attr_filter.accounting_response" from file
/etc/freeradius/mods-enabled/attr_filter
attr_filter attr_filter.accounting_response {
filename =
"/etc/freeradius/mods-config/attr_filter/accounting_response"
key = "%{User-Name}"
relaxed = no
}
# Loading module "attr_filter.coa" from file
/etc/freeradius/mods-enabled/attr_filter
attr_filter attr_filter.coa {
filename = "/etc/freeradius/mods-config/attr_filter/coa"
key = "%{User-Name}"
relaxed = no
}
# Loaded module rlm_chap
# Loading module "chap" from file /etc/freeradius/mods-enabled/chap
# Loaded module rlm_date
# Loading module "date" from file /etc/freeradius/mods-enabled/date
date {
format = "%b %e %Y %H:%M:%S %Z"
utc = no
}
# Loading module "wispr2date" from file /etc/freeradius/mods-enabled/date
date wispr2date {
format = "%Y-%m-%dT%H:%M:%S"
utc = no
}
# Loaded module rlm_detail
# Loading module "detail" from file /etc/freeradius/mods-enabled/detail
detail {
filename =
"@radacctdir@/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
header = "%t"
permissions = 384
locking = no
dates_as_integer = no
escape_filenames = no
log_packet_header = no
}
# Loading module "auth_log" from file /etc/freeradius/mods-enabled/detail.log
detail auth_log {
filename =
"@radacctdir@/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d"
header = "%t"
permissions = 384
locking = no
dates_as_integer = no
escape_filenames = no
log_packet_header = no
}
# Loading module "reply_log" from file /etc/freeradius/mods-enabled/detail.log
detail reply_log {
filename =
"@radacctdir@/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d"
header = "%t"
permissions = 384
locking = no
dates_as_integer = no
escape_filenames = no
log_packet_header = no
}
# Loading module "pre_proxy_log" from file
/etc/freeradius/mods-enabled/detail.log
detail pre_proxy_log {
filename =
"@radacctdir@/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d"
header = "%t"
permissions = 384
locking = no
dates_as_integer = no
escape_filenames = no
log_packet_header = no
}
# Loading module "post_proxy_log" from file
/etc/freeradius/mods-enabled/detail.log
detail post_proxy_log {
filename =
"@radacctdir@/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d"
header = "%t"
permissions = 384
locking = no
dates_as_integer = no
escape_filenames = no
log_packet_header = no
}
# Loaded module rlm_digest
# Loading module "digest" from file /etc/freeradius/mods-enabled/digest
# Loaded module rlm_dynamic_clients
# Loading module "dynamic_clients" from file
/etc/freeradius/mods-enabled/dynamic_clients
# Loaded module rlm_eap
# Loading module "eap" from file /etc/freeradius/mods-enabled/eap
eap {
default_eap_type = "tls"
timer_expire = 60
max_eap_type = 52
ignore_unknown_eap_types = no
cisco_accounting_username_bug = no
max_sessions = 16384
dedup_key = "%{Calling-Station-Id}"
}
# Loaded module rlm_exec
# Loading module "echo" from file /etc/freeradius/mods-enabled/echo
exec echo {
wait = yes
program = "/bin/echo %{User-Name}"
input_pairs = "request"
output_pairs = "reply"
shell_escape = yes
}
# Loading module "exec" from file /etc/freeradius/mods-enabled/exec
exec {
wait = no
input_pairs = "request"
shell_escape = yes
timeout = 10
}
# Loaded module rlm_expiration
# Loading module "expiration" from file
/etc/freeradius/mods-enabled/expiration
# Loaded module rlm_expr
# Loading module "expr" from file /etc/freeradius/mods-enabled/expr
expr {
safe_characters =
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_:
/äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ"
}
# Loaded module rlm_files
# Loading module "files" from file /etc/freeradius/mods-enabled/files
files {
filename = "/etc/freeradius/mods-config/files/authorize"
acctusersfile = "/etc/freeradius/mods-config/files/accounting"
preproxy_usersfile = "/etc/freeradius/mods-config/files/pre-proxy"
}
# Loaded module rlm_linelog
# Loading module "linelog" from file /etc/freeradius/mods-enabled/linelog
linelog {
filename = "@logdir@/linelog"
escape_filenames = no
syslog_severity = "info"
permissions = 384
format = "This is a log message for %{User-Name}"
reference = "messages.%{%{reply:Packet-Type}:-default}"
}
# Loading module "log_accounting" from file
/etc/freeradius/mods-enabled/linelog
linelog log_accounting {
filename = "@logdir@/linelog-accounting"
escape_filenames = no
syslog_severity = "info"
permissions = 384
format = ""
reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}"
}
# Loaded module rlm_logintime
# Loading module "logintime" from file /etc/freeradius/mods-enabled/logintime
logintime {
minimum_timeout = 60
}
# Loaded module rlm_mschap
# Loading module "mschap" from file /etc/freeradius/mods-enabled/mschap
mschap {
use_mppe = yes
require_encryption = no
require_strong = no
with_ntdomain_hack = yes
passchange {
}
allow_retry = yes
winbind_retry_with_normalised_username = no
}
# Loading module "ntlm_auth" from file /etc/freeradius/mods-enabled/ntlm_auth
exec ntlm_auth {
wait = yes
program = "/path/to/ntlm_auth --request-nt-key
--domain=MYDOMAIN --username=%{mschap:User-Name}
--password=%{User-Password}"
shell_escape = yes
}
# Loaded module rlm_pap
# Loading module "pap" from file /etc/freeradius/mods-enabled/pap
pap {
normalise = yes
}
# Loaded module rlm_passwd
# Loading module "etc_passwd" from file /etc/freeradius/mods-enabled/passwd
passwd etc_passwd {
filename = "/etc/passwd"
format = "*User-Name:Crypt-Password:"
delimiter = ":"
ignore_nislike = no
ignore_empty = yes
allow_multiple_keys = no
hash_size = 100
}
# Loaded module rlm_preprocess
# Loading module "preprocess" from file
/etc/freeradius/mods-enabled/preprocess
preprocess {
huntgroups = "/etc/freeradius/mods-config/preprocess/huntgroups"
hints = "/etc/freeradius/mods-config/preprocess/hints"
with_ascend_hack = no
ascend_channels_per_line = 23
with_ntdomain_hack = no
with_specialix_jetstream_hack = no
with_cisco_vsa_hack = no
with_alvarion_vsa_hack = no
}
# Loaded module rlm_proxy_rate_limit
# Loading module "proxy_rate_limit" from file
/etc/freeradius/mods-enabled/proxy_rate_limit
proxy_rate_limit {
max_entries = 2048
idle_timeout = 10
num_subtables = 256
window = 1
}
# Loaded module rlm_radutmp
# Loading module "radutmp" from file /etc/freeradius/mods-enabled/radutmp
radutmp {
filename = "@logdir@/radutmp"
username = "%{User-Name}"
case_sensitive = yes
check_with_nas = yes
permissions = 384
caller_id = yes
}
# Loaded module rlm_realm
# Loading module "IPASS" from file /etc/freeradius/mods-enabled/realm
realm IPASS {
format = "prefix"
delimiter = "/"
ignore_default = no
ignore_null = no
}
# Loading module "suffix" from file /etc/freeradius/mods-enabled/realm
realm suffix {
format = "suffix"
delimiter = "@"
ignore_default = no
ignore_null = no
}
# Loading module "bangpath" from file /etc/freeradius/mods-enabled/realm
realm bangpath {
format = "prefix"
delimiter = "!"
ignore_default = no
ignore_null = no
}
# Loading module "realmpercent" from file /etc/freeradius/mods-enabled/realm
realm realmpercent {
format = "suffix"
delimiter = "%"
ignore_default = no
ignore_null = no
}
# Loading module "ntdomain" from file /etc/freeradius/mods-enabled/realm
realm ntdomain {
format = "prefix"
delimiter = "\"
ignore_default = no
ignore_null = no
}
# Loaded module rlm_replicate
# Loading module "replicate" from file /etc/freeradius/mods-enabled/replicate
# Loaded module rlm_soh
# Loading module "soh" from file /etc/freeradius/mods-enabled/soh
soh {
dhcp = yes
}
# Loading module "sradutmp" from file /etc/freeradius/mods-enabled/sradutmp
radutmp sradutmp {
filename = "@logdir@/sradutmp"
username = "%{User-Name}"
case_sensitive = yes
check_with_nas = yes
permissions = 420
caller_id = no
}
# Loaded module rlm_totp
# Loading module "totp" from file /etc/freeradius/mods-enabled/totp
totp {
time_step = 30
otp_length = 6
lookback_steps = 1
lookback_interval = 30
lookforward_steps = 0
}
# Loaded module rlm_unix
# Loading module "unix" from file /etc/freeradius/mods-enabled/unix
unix {
radwtmp = "@logdir@/radwtmp"
}
Creating attribute Unix-Group
# Loaded module rlm_unpack
# Loading module "unpack" from file /etc/freeradius/mods-enabled/unpack
# Loaded module rlm_utf8
# Loading module "utf8" from file /etc/freeradius/mods-enabled/utf8
instantiate {
}
# Instantiating module "reject" from file /etc/freeradius/mods-enabled/always
# Instantiating module "fail" from file /etc/freeradius/mods-enabled/always
# Instantiating module "ok" from file /etc/freeradius/mods-enabled/always
# Instantiating module "handled" from file /etc/freeradius/mods-enabled/always
# Instantiating module "invalid" from file /etc/freeradius/mods-enabled/always
# Instantiating module "userlock" from file
/etc/freeradius/mods-enabled/always
# Instantiating module "notfound" from file
/etc/freeradius/mods-enabled/always
# Instantiating module "noop" from file /etc/freeradius/mods-enabled/always
# Instantiating module "updated" from file /etc/freeradius/mods-enabled/always
# Instantiating module "attr_filter.post-proxy" from file
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/post-proxy
# Instantiating module "attr_filter.pre-proxy" from file
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/pre-proxy
# Instantiating module "attr_filter.access_reject" from file
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/access_reject
# Instantiating module "attr_filter.access_challenge" from file
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/access_challenge
# Instantiating module "attr_filter.accounting_response" from file
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file
/etc/freeradius/mods-config/attr_filter/accounting_response
# Instantiating module "attr_filter.coa" from file
/etc/freeradius/mods-enabled/attr_filter
reading pairlist file /etc/freeradius/mods-config/attr_filter/coa
# Instantiating module "detail" from file /etc/freeradius/mods-enabled/detail
# Instantiating module "auth_log" from file
/etc/freeradius/mods-enabled/detail.log
rlm_detail (auth_log): 'User-Password' suppressed, will not appear in
detail output
# Instantiating module "reply_log" from file
/etc/freeradius/mods-enabled/detail.log
# Instantiating module "pre_proxy_log" from file
/etc/freeradius/mods-enabled/detail.log
# Instantiating module "post_proxy_log" from file
/etc/freeradius/mods-enabled/detail.log
/etc/freeradius/mods-enabled/eap[56]: Found dynamic expansion in
string which will not be dynamically expanded
# Instantiating module "eap" from file /etc/freeradius/mods-enabled/eap
# Linked to sub-module rlm_eap_md5
# Linked to sub-module rlm_eap_gtc
gtc {
challenge = "Password: "
auth_type = "PAP"
}
# Linked to sub-module rlm_eap_tls
tls {
tls = "tls-common"
virtual_server = "check-eap-tls"
}
tls-config tls-common {
verify_depth = 0
ca_path = "/etc/freeradius/certs"
pem_file_type = yes
private_key_file = "/etc/freeradius/certs/server.pem"
certificate_file = "/etc/freeradius/certs/server.pem"
ca_file = "/etc/freeradius/certs/ca.pem"
private_key_password = <<< secret >>>
fragment_size = 1024
include_length = yes
auto_chain = yes
check_crl = no
check_all_crl = no
ca_path_reload_interval = 0
cipher_list = "DEFAULT"
cipher_server_preference = no
reject_unknown_intermediate_ca = no
ecdh_curve = ""
tls_max_version = "1.3"
tls_min_version = "1.2"
cache {
enable = no
lifetime = 24
max_entries = 255
}
verify {
skip_if_ocsp_ok = no
}
ocsp {
enable = no
override_cert_url = yes
url = "http://127.0.0.1/ocsp/"
use_nonce = yes
timeout = 0
softfail = no
}
}
# Linked to sub-module rlm_eap_ttls
ttls {
tls = "tls-common"
default_eap_type = "md5"
copy_request_to_tunnel = no
use_tunneled_reply = no
virtual_server = "inner-tunnel"
include_length = yes
require_client_cert = no
}
tls: Using cached TLS configuration from previous invocation
# Linked to sub-module rlm_eap_peap
peap {
tls = "tls-common"
default_eap_type = "mschapv2"
copy_request_to_tunnel = no
use_tunneled_reply = no
proxy_tunneled_request_as_eap = yes
virtual_server = "inner-tunnel"
soh = no
require_client_cert = no
}
tls: Using cached TLS configuration from previous invocation
# Linked to sub-module rlm_eap_mschapv2
mschapv2 {
with_ntdomain_hack = no
send_error = no
}
# Instantiating module "expiration" from file
/etc/freeradius/mods-enabled/expiration
# Instantiating module "files" from file /etc/freeradius/mods-enabled/files
reading pairlist file /etc/freeradius/mods-config/files/authorize
reading pairlist file /etc/freeradius/mods-config/files/accounting
reading pairlist file /etc/freeradius/mods-config/files/pre-proxy
# Instantiating module "linelog" from file
/etc/freeradius/mods-enabled/linelog
# Instantiating module "log_accounting" from file
/etc/freeradius/mods-enabled/linelog
# Instantiating module "logintime" from file
/etc/freeradius/mods-enabled/logintime
# Instantiating module "mschap" from file /etc/freeradius/mods-enabled/mschap
rlm_mschap (mschap): using internal authentication
# Instantiating module "pap" from file /etc/freeradius/mods-enabled/pap
# Instantiating module "etc_passwd" from file
/etc/freeradius/mods-enabled/passwd
rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no
# Instantiating module "preprocess" from file
/etc/freeradius/mods-enabled/preprocess
reading pairlist file /etc/freeradius/mods-config/preprocess/huntgroups
reading pairlist file /etc/freeradius/mods-config/preprocess/hints
# Instantiating module "proxy_rate_limit" from file
/etc/freeradius/mods-enabled/proxy_rate_limit
# Instantiating module "IPASS" from file /etc/freeradius/mods-enabled/realm
# Instantiating module "suffix" from file /etc/freeradius/mods-enabled/realm
# Instantiating module "bangpath" from file /etc/freeradius/mods-enabled/realm
# Instantiating module "realmpercent" from file
/etc/freeradius/mods-enabled/realm
# Instantiating module "ntdomain" from file /etc/freeradius/mods-enabled/realm
# Instantiating module "totp" from file /etc/freeradius/mods-enabled/totp
} # modules
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/freeradius/radiusd.conf
} # server
server default { # from file /etc/freeradius/sites-enabled/default
# Loading authenticate {...}
Compiling Auth-Type PAP for attr Auth-Type
Compiling Auth-Type CHAP for attr Auth-Type
Compiling Auth-Type MS-CHAP for attr Auth-Type
# Loading authorize {...}
Ignoring "sql" (see raddb/mods-available/README.rst)
Ignoring "ldap" (see raddb/mods-available/README.rst)
Compiling Autz-Type New-TLS-Connection for attr Autz-Type
# Loading preacct {...}
# Loading accounting {...}
# Loading post-auth {...}
Compiling Post-Auth-Type REJECT for attr Post-Auth-Type
Compiling Post-Auth-Type Challenge for attr Post-Auth-Type
Compiling Post-Auth-Type Client-Lost for attr Post-Auth-Type
} # server default
server inner-tunnel { # from file /etc/freeradius/sites-enabled/inner-tunnel
# Loading authenticate {...}
Compiling Auth-Type PAP for attr Auth-Type
Compiling Auth-Type CHAP for attr Auth-Type
Compiling Auth-Type MS-CHAP for attr Auth-Type
# Loading authorize {...}
# Loading post-proxy {...}
# Loading post-auth {...}
# Skipping contents of 'if' as it is always 'false' --
/etc/freeradius/sites-enabled/inner-tunnel:366
Compiling Post-Auth-Type REJECT for attr Post-Auth-Type
} # server inner-tunnel
server check-eap-tls { # from file /etc/freeradius/sites-enabled/check-eap-tls
# Loading authorize {...}
} # server check-eap-tls
server status { # from file /etc/freeradius/sites-enabled/status
# Loading authorize {...}
Compiling Autz-Type Status-Server for attr Autz-Type
} # server status
radiusd: #### Opening IP addresses and Ports ####
listen {
type = "auth"
ipaddr = *
port = 0
limit {
max_connections = 128
lifetime = 30
idle_timeout = 60
}
Setting idle_timeout to 0
}
listen {
type = "acct"
ipaddr = *
port = 0
limit {
max_connections = 16
lifetime = 0
idle_timeout = 30
}
}
listen {
type = "auth"
ipv6addr = ::
port = 0
limit {
max_connections = 16
lifetime = 0
idle_timeout = 30
}
}
listen {
type = "acct"
ipv6addr = ::
port = 0
limit {
max_connections = 16
lifetime = 0
idle_timeout = 30
}
}
listen {
type = "auth"
ipaddr = 127.0.0.1
port = 18120
}
listen {
type = "status"
ipaddr = *
port = 8080
proto = "tcp"
limit {
max_connections = 64
lifetime = 15
idle_timeout = 30
}
Setting idle_timeout to 0
clients = "azure_lb"
client azure_lb {
ipaddr = 168.63.129.16
secret = <<< secret >>>
proto = "tcp"
limit {
max_connections = 16
lifetime = 0
idle_timeout = 30
}
}
}
Listening on auth address * port 1812 bound to server default
Listening on acct address * port 1813 bound to server default
Listening on auth address :: port 1812 bound to server default
Listening on acct address :: port 1813 bound to server default
Listening on auth address 127.0.0.1 port 18120 bound to server inner-tunnel
Listening on status proto tcp address * port 8080 bound to server status
Listening on proxy address * port 47097
Listening on proxy address :: port 33982
Ready to process requests
... new connection request on TCP socket
Listening on status from client (168.63.129.16, 54790) -> (*, 8080,
virtual-server=status)
Waking up in 0.8 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 54790) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 54858) -> (*, 8080,
virtual-server=status)
Waking up in 0.8 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 54858) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 54946) -> (*, 8080,
virtual-server=status)
Waking up in 0.8 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 54946) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55023) -> (*, 8080,
virtual-server=status)
Waking up in 0.8 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55023) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55116) -> (*, 8080,
virtual-server=status)
Waking up in 0.8 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55116) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55188) -> (*, 8080,
virtual-server=status)
Waking up in 0.8 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55188) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55271) -> (*, 8080,
virtual-server=status)
Waking up in 0.8 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55271) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55341) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55341) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55411) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55411) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55486) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55486) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55575) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55575) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55631) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55631) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55721) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55721) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55790) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55790) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 55987) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 55987) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Failed inserting TCP socket into parent list.
Listening on status from client (168.63.129.16, 56081) -> (*, 8080,
virtual-server=status)
Waking up in 0.7 seconds.
Waking up in 14.4 seconds.
Client has closed connection
... shutting down socket status from client (168.63.129.16, 56081) ->
(*, 8080, virtual-server=status)
Ready to process requests
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Ready to process requests
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Ready to process requests
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Ready to process requests
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Ready to process requests
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Ready to process requests
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Ready to process requests
(0) Received Access-Request Id 225 from 185.241.224.187:35356 to
10.0.0.5:1812 length 195
(0) NAS-IP-Address = 192.168.1.112
(0) NAS-Port-Type = Ethernet
(0) NAS-Port = 4
(0) User-Name = "host/353ec014-5c70-4e4c-a7c8-b9c8b5860352"
(0) Acct-Session-Id = "0500003F"
(0) Called-Station-Id = "A4-B2-39-CD-FE-3F"
(0) Calling-Station-Id = "28-00-AF-0F-A8-32"
(0) EAP-Message =
0x0201002e01686f73742f33353365633031342d356337302d346534632d613763382d623963386235383630333532
(0) Message-Authenticator = 0xfd9f875e95feb822675be4ee23747c02
(0) # Executing section authorize from file
/etc/freeradius/sites-enabled/default
(0) authorize {
(0) policy filter_username {
(0) if (&User-Name) {
(0) if (&User-Name) -> TRUE
(0) if (&User-Name) {
(0) if (&User-Name =~ / /) {
(0) if (&User-Name =~ / /) -> FALSE
(0) if (&User-Name =~ /@[^@]*@/ ) {
(0) if (&User-Name =~ /@[^@]*@/ ) -> FALSE
(0) if (&User-Name =~ /\.\./ ) {
(0) if (&User-Name =~ /\.\./ ) -> FALSE
(0) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/)) {
(0) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/))
-> FALSE
(0) if (&User-Name =~ /\.$/) {
(0) if (&User-Name =~ /\.$/) -> FALSE
(0) if (&User-Name =~ /(a)\./) {
(0) if (&User-Name =~ /(a)\./) -> FALSE
(0) } # if (&User-Name) = notfound
(0) } # policy filter_username = notfound
(0) [preprocess] = ok
(0) [chap] = noop
(0) [mschap] = noop
(0) [digest] = noop
(0) suffix: Checking for suffix after "@"
(0) suffix: No '@' in User-Name =
"host/353ec014-5c70-4e4c-a7c8-b9c8b5860352", looking up realm NULL
(0) suffix: No such realm "NULL"
(0) [suffix] = noop
(0) eap: Peer sent EAP Response (code 2) ID 1 length 46
(0) eap: EAP-Identity reply, returning 'ok' so we can short-circuit
the rest of authorize
(0) [eap] = ok
(0) } # authorize = ok
(0) Found Auth-Type = eap
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0) authenticate {
(0) eap: EXPAND %{Calling-Station-Id}
(0) eap: --> 28-00-AF-0F-A8-32
(0) eap: Peer sent packet with method EAP Identity (1)
(0) eap: Using default_eap_type = TLS
(0) eap: Calling submodule eap_tls to process data
(0) eap_tls: (TLS) TLS -Initiating new session
(0) eap_tls: (TLS) TLS - Setting verify mode to require certificate from client
(0) eap: Sending EAP Request (code 1) ID 2 length 6
(0) eap: EAP session adding &reply:State = 0xa960c76da962ca67
(0) [eap] = handled
(0) } # authenticate = handled
(0) Using Post-Auth-Type Challenge
(0) # Executing group from file /etc/freeradius/sites-enabled/default
(0) Challenge { ... } # empty sub-section is ignored
(0) session-state: Saving cached attributes
(0) Framed-MTU = 994
(0) Sent Access-Challenge Id 225 from 10.0.0.5:1812 to
185.241.224.187:35356 length 64
(0) EAP-Message = 0x010200060d20
(0) Message-Authenticator = 0x00000000000000000000000000000000
(0) State = 0xa960c76da962ca67c033c547c41b2362
(0) Finished request
Waking up in 29.9 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 25.3 seconds.
(1) Received Access-Request Id 226 from 185.241.224.187:35356 to
10.0.0.5:1812 length 436
(1) NAS-IP-Address = 192.168.1.112
(1) NAS-Port-Type = Ethernet
(1) NAS-Port = 4
(1) User-Name = "host/353ec014-5c70-4e4c-a7c8-b9c8b5860352"
(1) Acct-Session-Id = "0500003F"
(1) State = 0xa960c76da962ca67c033c547c41b2362
(1) Called-Station-Id = "A4-B2-39-CD-FE-3F"
(1) Calling-Station-Id = "28-00-AF-0F-A8-32"
(1) EAP-Message =
0x0202010b0d800000010116030100fc010000f803038005f5c1f744d3396f887f73d0f6ee32038ac5ea916872900f35e3a30829a89920a00f349108c0a3d8a1825562e0cfc7691b328ffe45f9025dc72d0170776b431f002813021301c02cc02bc030c02fc024c023c028c027c00ac009c014c013009d009c003d003c0035002f01000087000500050100000000002b0009080304030303020301000d001a001808040805080604010501020104030503020302020601060300230000000a00080006001d00170018000b00020100003300260024001d00200d9fc1fc2438e7552301836ca01472e47d655008f1650ff4e35dd57955b80b590031000000170000ff01000100002d00020101
(1) Message-Authenticator = 0xcc559b3212a95756ea6a742fc1963909
(1) Restoring &session-state
(1) &session-state:Framed-MTU = 994
(1) # Executing section authorize from file
/etc/freeradius/sites-enabled/default
(1) authorize {
(1) policy filter_username {
(1) if (&User-Name) {
(1) if (&User-Name) -> TRUE
(1) if (&User-Name) {
(1) if (&User-Name =~ / /) {
(1) if (&User-Name =~ / /) -> FALSE
(1) if (&User-Name =~ /@[^@]*@/ ) {
(1) if (&User-Name =~ /@[^@]*@/ ) -> FALSE
(1) if (&User-Name =~ /\.\./ ) {
(1) if (&User-Name =~ /\.\./ ) -> FALSE
(1) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/)) {
(1) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/))
-> FALSE
(1) if (&User-Name =~ /\.$/) {
(1) if (&User-Name =~ /\.$/) -> FALSE
(1) if (&User-Name =~ /(a)\./) {
(1) if (&User-Name =~ /(a)\./) -> FALSE
(1) } # if (&User-Name) = notfound
(1) } # policy filter_username = notfound
(1) [preprocess] = ok
(1) [chap] = noop
(1) [mschap] = noop
(1) [digest] = noop
(1) suffix: Checking for suffix after "@"
(1) suffix: No '@' in User-Name =
"host/353ec014-5c70-4e4c-a7c8-b9c8b5860352", looking up realm NULL
(1) suffix: No such realm "NULL"
(1) [suffix] = noop
(1) eap: Peer sent EAP Response (code 2) ID 2 length 267
(1) eap: No EAP Start, assuming it's an on-going EAP conversation
(1) [eap] = updated
(1) [files] = noop
(1) [expiration] = noop
(1) [logintime] = noop
(1) [pap] = noop
(1) } # authorize = updated
(1) Found Auth-Type = eap
(1) # Executing group from file /etc/freeradius/sites-enabled/default
(1) authenticate {
(1) eap: Removing EAP session with state 0xa960c76da962ca67
(1) eap: Previous EAP request found for state 0xa960c76da962ca67,
released from the list
(1) eap: Peer sent packet with method EAP TLS (13)
(1) eap: Calling submodule eap_tls to process data
(1) eap_tls: (TLS) EAP Peer says that the final record size will be 257 bytes
(1) eap_tls: (TLS) EAP Got all data (257 bytes)
(1) eap_tls: (TLS) TLS - Handshake state - before SSL initialization
(1) eap_tls: (TLS) TLS - Handshake state - Server before SSL initialization
(1) eap_tls: (TLS) TLS - Handshake state - Server before SSL initialization
(1) eap_tls: (TLS) TLS - recv TLS 1.3 Handshake, ClientHello
(1) eap_tls: (TLS) TLS - Handshake state - Server SSLv3/TLS read client hello
(1) eap_tls: (TLS) TLS - send TLS 1.3 Handshake, ServerHello
(1) eap_tls: (TLS) TLS - Handshake state - Server SSLv3/TLS write server hello
(1) eap_tls: (TLS) TLS - send TLS 1.3 ChangeCipherSpec
(1) eap_tls: (TLS) TLS - Handshake state - Server SSLv3/TLS write
change cipher spec
(1) eap_tls: (TLS) TLS - send TLS 1.3 Handshake, EncryptedExtensions
(1) eap_tls: (TLS) TLS - Handshake state - Server TLSv1.3 write
encrypted extensions
(1) eap_tls: (TLS) TLS - send TLS 1.3 Handshake, CertificateRequest
(1) eap_tls: (TLS) TLS - Handshake state - Server SSLv3/TLS write
certificate request
(1) eap_tls: (TLS) TLS - send TLS 1.3 Handshake, Certificate
(1) eap_tls: (TLS) TLS - Handshake state - Server SSLv3/TLS write certificate
(1) eap_tls: (TLS) TLS - send TLS 1.3 Handshake, CertificateVerify
(1) eap_tls: (TLS) TLS - Handshake state - Server TLSv1.3 write server
certificate verify
(1) eap_tls: (TLS) TLS - send TLS 1.3 Handshake, Finished
(1) eap_tls: (TLS) TLS - Handshake state - Server SSLv3/TLS write finished
(1) eap_tls: (TLS) TLS - Handshake state - Server TLSv1.3 early data
(1) eap_tls: (TLS) TLS - Server : Need to read more data: TLSv1.3 early data
(1) eap_tls: (TLS) TLS - In Handshake Phase
(1) eap: Sending EAP Request (code 1) ID 3 length 1000
(1) eap: EAP session adding &reply:State = 0xa960c76da863ca67
(1) [eap] = handled
(1) } # authenticate = handled
(1) Using Post-Auth-Type Challenge
(1) # Executing group from file /etc/freeradius/sites-enabled/default
(1) Challenge { ... } # empty sub-section is ignored
(1) session-state: Saving cached attributes
(1) Framed-MTU = 994
(1) TLS-Session-Information = "(TLS) TLS - recv TLS 1.3 Handshake,
ClientHello"
(1) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
ServerHello"
(1) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 ChangeCipherSpec"
(1) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
EncryptedExtensions"
(1) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateRequest"
(1) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
Certificate"
(1) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateVerify"
(1) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake, Finished"
(1) Sent Access-Challenge Id 226 from 10.0.0.5:1812 to
185.241.224.187:35356 length 1064
(1) EAP-Message =
0x010303e80dc000000fdb160303007a02000076030315b4bdf959e77a819ca5b04ba62a57391745e7bed4ed82f5b2439560cdb8d77120a00f349108c0a3d8a1825562e0cfc7691b328ffe45f9025dc72d0170776b431f130200002e002b0002030400330024001d00209639f2611dd7535b4db2fac1f7709482f843443278af0b934076fcb02adb07691403030001011703030017409bded55c5fc8075b8e54990bb1a5ed4c42044db7684a17030301374e577f3a09d50eb88f0faf4977b2c27762b050bce58ac056b15c39776ebb5b95a26cc8196b755d2cd2665ac7259691a99366935a39332f8f5da1eaa7c78ff13526f4ae124707f879a230e68b0f59b2993186a86c41085634dd4933ac77d752db76cbe073ca19402ddbdea29e830e8be189b692b124b9d0edfd5968d6859b86ac53e0dea8edf11683fec7c989c11829bb3d06e3fcd860fbf9bca4031b34066ad035591aa696988e6a7b4594ad4d96da7cb139927f81cf8b75785e86eb9865c2ec939a558bd7da98
(1) Message-Authenticator = 0x00000000000000000000000000000000
(1) State = 0xa960c76da863ca67c033c547c41b2362
(1) Finished request
Waking up in 20.9 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 20.3 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 15.3 seconds.
(2) Received Access-Request Id 227 from 185.241.224.187:35356 to
10.0.0.5:1812 length 173
(2) NAS-IP-Address = 192.168.1.112
(2) NAS-Port-Type = Ethernet
(2) NAS-Port = 4
(2) User-Name = "host/353ec014-5c70-4e4c-a7c8-b9c8b5860352"
(2) Acct-Session-Id = "0500003F"
(2) State = 0xa960c76da863ca67c033c547c41b2362
(2) Called-Station-Id = "A4-B2-39-CD-FE-3F"
(2) Calling-Station-Id = "28-00-AF-0F-A8-32"
(2) EAP-Message = 0x020300060d00
(2) Message-Authenticator = 0xbf0af695bb5ac349944293a07a61472c
(2) Restoring &session-state
(2) &session-state:Framed-MTU = 994
(2) &session-state:TLS-Session-Information = "(TLS) TLS - recv TLS
1.3 Handshake, ClientHello"
(2) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, ServerHello"
(2) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 ChangeCipherSpec"
(2) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, EncryptedExtensions"
(2) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateRequest"
(2) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Certificate"
(2) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateVerify"
(2) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Finished"
(2) # Executing section authorize from file
/etc/freeradius/sites-enabled/default
(2) authorize {
(2) policy filter_username {
(2) if (&User-Name) {
(2) if (&User-Name) -> TRUE
(2) if (&User-Name) {
(2) if (&User-Name =~ / /) {
(2) if (&User-Name =~ / /) -> FALSE
(2) if (&User-Name =~ /@[^@]*@/ ) {
(2) if (&User-Name =~ /@[^@]*@/ ) -> FALSE
(2) if (&User-Name =~ /\.\./ ) {
(2) if (&User-Name =~ /\.\./ ) -> FALSE
(2) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/)) {
(2) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/))
-> FALSE
(2) if (&User-Name =~ /\.$/) {
(2) if (&User-Name =~ /\.$/) -> FALSE
(2) if (&User-Name =~ /(a)\./) {
(2) if (&User-Name =~ /(a)\./) -> FALSE
(2) } # if (&User-Name) = notfound
(2) } # policy filter_username = notfound
(2) [preprocess] = ok
(2) [chap] = noop
(2) [mschap] = noop
(2) [digest] = noop
(2) suffix: Checking for suffix after "@"
(2) suffix: No '@' in User-Name =
"host/353ec014-5c70-4e4c-a7c8-b9c8b5860352", looking up realm NULL
(2) suffix: No such realm "NULL"
(2) [suffix] = noop
(2) eap: Peer sent EAP Response (code 2) ID 3 length 6
(2) eap: No EAP Start, assuming it's an on-going EAP conversation
(2) [eap] = updated
(2) [files] = noop
(2) [expiration] = noop
(2) [logintime] = noop
(2) [pap] = noop
(2) } # authorize = updated
(2) Found Auth-Type = eap
(2) # Executing group from file /etc/freeradius/sites-enabled/default
(2) authenticate {
(2) eap: Removing EAP session with state 0xa960c76da863ca67
(2) eap: Previous EAP request found for state 0xa960c76da863ca67,
released from the list
(2) eap: Peer sent packet with method EAP TLS (13)
(2) eap: Calling submodule eap_tls to process data
(2) eap_tls: (TLS) Peer ACKed our handshake fragment
(2) eap: Sending EAP Request (code 1) ID 4 length 1000
(2) eap: EAP session adding &reply:State = 0xa960c76dab64ca67
(2) [eap] = handled
(2) } # authenticate = handled
(2) Using Post-Auth-Type Challenge
(2) # Executing group from file /etc/freeradius/sites-enabled/default
(2) Challenge { ... } # empty sub-section is ignored
(2) session-state: Saving cached attributes
(2) Framed-MTU = 994
(2) TLS-Session-Information = "(TLS) TLS - recv TLS 1.3 Handshake,
ClientHello"
(2) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
ServerHello"
(2) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 ChangeCipherSpec"
(2) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
EncryptedExtensions"
(2) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateRequest"
(2) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
Certificate"
(2) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateVerify"
(2) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake, Finished"
(2) Sent Access-Challenge Id 227 from 10.0.0.5:1812 to
185.241.224.187:35356 length 1064
(2) EAP-Message =
0x010403e80dc000000fdb84c7ccd063df8e12ce32a75073a57effe537a56c29818a6504d97dab790416413b46b17874bba4f1100faf0fc2d2794404aa403cb3fe415ae48beef376503db654447da19846fbc6dca88ff672bf7cf52411b8f42cd7ce4299213643779e3256bcc8b4209fd9fade5435a7e71b6d153be151825c1728fd9fe028c1c7fe9fe04fb257e10a2454579941cc4bef65d9df55242b70decd2f44985ee48067aee6da01461f7d86a46d0ecb29116e61491008a2cd180beca8f4321694f16a08f7644ac0eed1e86b62882ec874693f8a24c8f238edd83a65a8450ecd940d216eb93cf1be989493c72f316f39e9bd8b7c1e7eb504c51a94ce5ce4152d454a4132ce4cd4da25602148a1c9899b5dc3db9308da9b313a12707580e0185321c529dd5546826408b642fed98afee0181181f212df866466f4dfabaa6b1517e009b0c9af3ab43f0561fe38aa9bb2d02d85581f726562eac8099e62c51ec1575e1b19713a426667cbb555f02d0e14540ec47a4c65
(2) Message-Authenticator = 0x00000000000000000000000000000000
(2) State = 0xa960c76dab64ca67c033c547c41b2362
(2) Finished request
Waking up in 11.8 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 10.3 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 5.3 seconds.
(3) Received Access-Request Id 228 from 185.241.224.187:35356 to
10.0.0.5:1812 length 173
(3) NAS-IP-Address = 192.168.1.112
(3) NAS-Port-Type = Ethernet
(3) NAS-Port = 4
(3) User-Name = "host/353ec014-5c70-4e4c-a7c8-b9c8b5860352"
(3) Acct-Session-Id = "0500003F"
(3) State = 0xa960c76dab64ca67c033c547c41b2362
(3) Called-Station-Id = "A4-B2-39-CD-FE-3F"
(3) Calling-Station-Id = "28-00-AF-0F-A8-32"
(3) EAP-Message = 0x020400060d00
(3) Message-Authenticator = 0x038ead73ce4c8b0a455b6b5ffea2c726
(3) Restoring &session-state
(3) &session-state:Framed-MTU = 994
(3) &session-state:TLS-Session-Information = "(TLS) TLS - recv TLS
1.3 Handshake, ClientHello"
(3) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, ServerHello"
(3) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 ChangeCipherSpec"
(3) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, EncryptedExtensions"
(3) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateRequest"
(3) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Certificate"
(3) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateVerify"
(3) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Finished"
(3) # Executing section authorize from file
/etc/freeradius/sites-enabled/default
(3) authorize {
(3) policy filter_username {
(3) if (&User-Name) {
(3) if (&User-Name) -> TRUE
(3) if (&User-Name) {
(3) if (&User-Name =~ / /) {
(3) if (&User-Name =~ / /) -> FALSE
(3) if (&User-Name =~ /@[^@]*@/ ) {
(3) if (&User-Name =~ /@[^@]*@/ ) -> FALSE
(3) if (&User-Name =~ /\.\./ ) {
(3) if (&User-Name =~ /\.\./ ) -> FALSE
(3) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/)) {
(3) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/))
-> FALSE
(3) if (&User-Name =~ /\.$/) {
(3) if (&User-Name =~ /\.$/) -> FALSE
(3) if (&User-Name =~ /(a)\./) {
(3) if (&User-Name =~ /(a)\./) -> FALSE
(3) } # if (&User-Name) = notfound
(3) } # policy filter_username = notfound
(3) [preprocess] = ok
(3) [chap] = noop
(3) [mschap] = noop
(3) [digest] = noop
(3) suffix: Checking for suffix after "@"
(3) suffix: No '@' in User-Name =
"host/353ec014-5c70-4e4c-a7c8-b9c8b5860352", looking up realm NULL
(3) suffix: No such realm "NULL"
(3) [suffix] = noop
(3) eap: Peer sent EAP Response (code 2) ID 4 length 6
(3) eap: No EAP Start, assuming it's an on-going EAP conversation
(3) [eap] = updated
(3) [files] = noop
(3) [expiration] = noop
(3) [logintime] = noop
(3) [pap] = noop
(3) } # authorize = updated
(3) Found Auth-Type = eap
(3) # Executing group from file /etc/freeradius/sites-enabled/default
(3) authenticate {
(3) eap: Removing EAP session with state 0xa960c76dab64ca67
(3) eap: Previous EAP request found for state 0xa960c76dab64ca67,
released from the list
(3) eap: Peer sent packet with method EAP TLS (13)
(3) eap: Calling submodule eap_tls to process data
(3) eap_tls: (TLS) Peer ACKed our handshake fragment
(3) eap: Sending EAP Request (code 1) ID 5 length 1000
(3) eap: EAP session adding &reply:State = 0xa960c76daa65ca67
(3) [eap] = handled
(3) } # authenticate = handled
(3) Using Post-Auth-Type Challenge
(3) # Executing group from file /etc/freeradius/sites-enabled/default
(3) Challenge { ... } # empty sub-section is ignored
(3) session-state: Saving cached attributes
(3) Framed-MTU = 994
(3) TLS-Session-Information = "(TLS) TLS - recv TLS 1.3 Handshake,
ClientHello"
(3) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
ServerHello"
(3) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 ChangeCipherSpec"
(3) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
EncryptedExtensions"
(3) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateRequest"
(3) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
Certificate"
(3) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateVerify"
(3) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake, Finished"
(3) Sent Access-Challenge Id 228 from 10.0.0.5:1812 to
185.241.224.187:35356 length 1064
(3) EAP-Message =
0x010503e80dc000000fdbfdab766478f6463a45aa0725242e5aac5c6de5a81c7a5bcdf1125abf8d15a3d47dc9e78f5ce707fc1038b6db94138a1e4ec53dd19ab470e420b2a3dec644d03d54d4887375596eebf2da1fb12bd224812df10fdaa9894b56b6659ccfed8754f9a842fa5800b4d6a99b4ab4004ec005e25ba1301b74ada5d51589796de8e21b482c9d02032bad52ce8e9d8d5b066b6261bd66d0a002c791ed18ca56a88fce85f07832b6a056be190b627d74030116c2e68266791db3a65391cea7149b66175d06b1cf01ccc3c3ee33bc83b2fe395b682d460944a8cad5aa1c3ba1a8332f129ed93f9f6e1e62e4f3098c0c51b04bded8f76ccb41538f865cd38429bd1e1fd29e74b727fae988371d89bc377ddbd58d1999d9b4f5408953930d6f2a9d2db76791726388811710c247f124ce94bb4284c064883e3bb525e539f7957e373b7e6cffbbb8790f7f1ef52db19cf876950d4eeb9218bd396d4844b8c56f9752308fc3ec27716a6f3b3676a1f0655d717ef1
(3) Message-Authenticator = 0x00000000000000000000000000000000
(3) State = 0xa960c76daa65ca67c033c547c41b2362
(3) Finished request
Waking up in 2.8 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 0.2 seconds.
(0) Cleaning up request packet ID 225 with timestamp +1392 due to
cleanup_delay was reached
Waking up in 9.0 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 4.3 seconds.
(4) Received Access-Request Id 229 from 185.241.224.187:35356 to
10.0.0.5:1812 length 173
(4) NAS-IP-Address = 192.168.1.112
(4) NAS-Port-Type = Ethernet
(4) NAS-Port = 4
(4) User-Name = "host/353ec014-5c70-4e4c-a7c8-b9c8b5860352"
(4) Acct-Session-Id = "0500003F"
(4) State = 0xa960c76daa65ca67c033c547c41b2362
(4) Called-Station-Id = "A4-B2-39-CD-FE-3F"
(4) Calling-Station-Id = "28-00-AF-0F-A8-32"
(4) EAP-Message = 0x020500060d00
(4) Message-Authenticator = 0xd41b050c3a3def192db05fd631f14607
(4) Restoring &session-state
(4) &session-state:Framed-MTU = 994
(4) &session-state:TLS-Session-Information = "(TLS) TLS - recv TLS
1.3 Handshake, ClientHello"
(4) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, ServerHello"
(4) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 ChangeCipherSpec"
(4) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, EncryptedExtensions"
(4) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateRequest"
(4) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Certificate"
(4) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateVerify"
(4) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Finished"
(4) # Executing section authorize from file
/etc/freeradius/sites-enabled/default
(4) authorize {
(4) policy filter_username {
(4) if (&User-Name) {
(4) if (&User-Name) -> TRUE
(4) if (&User-Name) {
(4) if (&User-Name =~ / /) {
(4) if (&User-Name =~ / /) -> FALSE
(4) if (&User-Name =~ /@[^@]*@/ ) {
(4) if (&User-Name =~ /@[^@]*@/ ) -> FALSE
(4) if (&User-Name =~ /\.\./ ) {
(4) if (&User-Name =~ /\.\./ ) -> FALSE
(4) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/)) {
(4) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/))
-> FALSE
(4) if (&User-Name =~ /\.$/) {
(4) if (&User-Name =~ /\.$/) -> FALSE
(4) if (&User-Name =~ /(a)\./) {
(4) if (&User-Name =~ /(a)\./) -> FALSE
(4) } # if (&User-Name) = notfound
(4) } # policy filter_username = notfound
(4) [preprocess] = ok
(4) [chap] = noop
(4) [mschap] = noop
(4) [digest] = noop
(4) suffix: Checking for suffix after "@"
(4) suffix: No '@' in User-Name =
"host/353ec014-5c70-4e4c-a7c8-b9c8b5860352", looking up realm NULL
(4) suffix: No such realm "NULL"
(4) [suffix] = noop
(4) eap: Peer sent EAP Response (code 2) ID 5 length 6
(4) eap: No EAP Start, assuming it's an on-going EAP conversation
(4) [eap] = updated
(4) [files] = noop
(4) [expiration] = noop
(4) [logintime] = noop
(4) [pap] = noop
(4) } # authorize = updated
(4) Found Auth-Type = eap
(4) # Executing group from file /etc/freeradius/sites-enabled/default
(4) authenticate {
(4) eap: Removing EAP session with state 0xa960c76daa65ca67
(4) eap: Previous EAP request found for state 0xa960c76daa65ca67,
released from the list
(4) eap: Peer sent packet with method EAP TLS (13)
(4) eap: Calling submodule eap_tls to process data
(4) eap_tls: (TLS) Peer ACKed our handshake fragment
(4) eap: Sending EAP Request (code 1) ID 6 length 1000
(4) eap: EAP session adding &reply:State = 0xa960c76dad66ca67
(4) [eap] = handled
(4) } # authenticate = handled
(4) Using Post-Auth-Type Challenge
(4) # Executing group from file /etc/freeradius/sites-enabled/default
(4) Challenge { ... } # empty sub-section is ignored
(4) session-state: Saving cached attributes
(4) Framed-MTU = 994
(4) TLS-Session-Information = "(TLS) TLS - recv TLS 1.3 Handshake,
ClientHello"
(4) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
ServerHello"
(4) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 ChangeCipherSpec"
(4) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
EncryptedExtensions"
(4) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateRequest"
(4) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
Certificate"
(4) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateVerify"
(4) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake, Finished"
(4) Sent Access-Challenge Id 229 from 10.0.0.5:1812 to
185.241.224.187:35356 length 1064
(4) EAP-Message =
0x010603e80dc000000fdbe1eb6d5ccf7a723ba08e36e26cc364e3deb8d6a570bf850795cab4233696941f9631498e9c58b86901eb823cd8b9a6c479d350dde4ce24b459b27012d0784767279b319e50647f62280123ba9f59784873b7adf37470625b66682b6074d34ed54a620756b34d798ea85c9cbd42cda44701be40ef5b6bb3b570530225344fa2062790bdb7f8ff1e107336771e0692f5e335e845b5f518a4cae2d4020cdb84e0faf68d26a7c335894fec424289a9188d141a1c9db099c6695c6201ecf88a3859d396441695dab5920e9ffc39488580ffe4a8631abf86e18838128d72317aea08617286bd7f2f0495be883e0ce8b15df6ad7be59c40e08081f324c89653320d697861b2a81b1cb9d917560ed71164f704dc0921aad85e80683672ee66ccdffd1faf573e4bd49b223f16ce62335ae5e423d1ef816de71f77ea19585566ad22f1de9b6488a425a2d78aa387929d435c21f229ba698d79b0073e4c3b382abbbfbfcb3ad815b8d472398f53d6048ea3f4
(4) Message-Authenticator = 0x00000000000000000000000000000000
(4) State = 0xa960c76dad66ca67c033c547c41b2362
(4) Finished request
Waking up in 2.8 seconds.
(1) Cleaning up request packet ID 226 with timestamp +1401 due to
cleanup_delay was reached
Waking up in 9.0 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 8.3 seconds.
... new connection request on TCP socket
Ignoring new connection due to client max_connections (16)
Waking up in 3.3 seconds.
(5) Received Access-Request Id 230 from 185.241.224.187:35356 to
10.0.0.5:1812 length 173
(5) NAS-IP-Address = 192.168.1.112
(5) NAS-Port-Type = Ethernet
(5) NAS-Port = 4
(5) User-Name = "host/353ec014-5c70-4e4c-a7c8-b9c8b5860352"
(5) Acct-Session-Id = "0500003F"
(5) State = 0xa960c76dad66ca67c033c547c41b2362
(5) Called-Station-Id = "A4-B2-39-CD-FE-3F"
(5) Calling-Station-Id = "28-00-AF-0F-A8-32"
(5) EAP-Message = 0x020600060d00
(5) Message-Authenticator = 0x0dc4ca0033f30b31ea12f0a84c3615c3
(5) Restoring &session-state
(5) &session-state:Framed-MTU = 994
(5) &session-state:TLS-Session-Information = "(TLS) TLS - recv TLS
1.3 Handshake, ClientHello"
(5) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, ServerHello"
(5) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 ChangeCipherSpec"
(5) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, EncryptedExtensions"
(5) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateRequest"
(5) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Certificate"
(5) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, CertificateVerify"
(5) &session-state:TLS-Session-Information = "(TLS) TLS - send TLS
1.3 Handshake, Finished"
(5) # Executing section authorize from file
/etc/freeradius/sites-enabled/default
(5) authorize {
(5) policy filter_username {
(5) if (&User-Name) {
(5) if (&User-Name) -> TRUE
(5) if (&User-Name) {
(5) if (&User-Name =~ / /) {
(5) if (&User-Name =~ / /) -> FALSE
(5) if (&User-Name =~ /@[^@]*@/ ) {
(5) if (&User-Name =~ /@[^@]*@/ ) -> FALSE
(5) if (&User-Name =~ /\.\./ ) {
(5) if (&User-Name =~ /\.\./ ) -> FALSE
(5) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/)) {
(5) if ((&User-Name =~ /@/) && (&User-Name !~ /(a)(.+)\.(.+)$/))
-> FALSE
(5) if (&User-Name =~ /\.$/) {
(5) if (&User-Name =~ /\.$/) -> FALSE
(5) if (&User-Name =~ /(a)\./) {
(5) if (&User-Name =~ /(a)\./) -> FALSE
(5) } # if (&User-Name) = notfound
(5) } # policy filter_username = notfound
(5) [preprocess] = ok
(5) [chap] = noop
(5) [mschap] = noop
(5) [digest] = noop
(5) suffix: Checking for suffix after "@"
(5) suffix: No '@' in User-Name =
"host/353ec014-5c70-4e4c-a7c8-b9c8b5860352", looking up realm NULL
(5) suffix: No such realm "NULL"
(5) [suffix] = noop
(5) eap: Peer sent EAP Response (code 2) ID 6 length 6
(5) eap: No EAP Start, assuming it's an on-going EAP conversation
(5) [eap] = updated
(5) [files] = noop
(5) [expiration] = noop
(5) [logintime] = noop
(5) [pap] = noop
(5) } # authorize = updated
(5) Found Auth-Type = eap
(5) # Executing group from file /etc/freeradius/sites-enabled/default
(5) authenticate {
(5) eap: Removing EAP session with state 0xa960c76dad66ca67
(5) eap: Previous EAP request found for state 0xa960c76dad66ca67,
released from the list
(5) eap: Peer sent packet with method EAP TLS (13)
(5) eap: Calling submodule eap_tls to process data
(5) eap_tls: (TLS) Peer ACKed our handshake fragment
(5) eap: Sending EAP Request (code 1) ID 7 length 109
(5) eap: EAP session adding &reply:State = 0xa960c76dac67ca67
(5) [eap] = handled
(5) } # authenticate = handled
(5) Using Post-Auth-Type Challenge
(5) # Executing group from file /etc/freeradius/sites-enabled/default
(5) Challenge { ... } # empty sub-section is ignored
(5) session-state: Saving cached attributes
(5) Framed-MTU = 994
(5) TLS-Session-Information = "(TLS) TLS - recv TLS 1.3 Handshake,
ClientHello"
(5) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
ServerHello"
(5) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 ChangeCipherSpec"
(5) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
EncryptedExtensions"
(5) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateRequest"
(5) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
Certificate"
(5) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake,
CertificateVerify"
(5) TLS-Session-Information = "(TLS) TLS - send TLS 1.3 Handshake, Finished"
(5) Sent Access-Challenge Id 230 from 10.0.0.5:1812 to
185.241.224.187:35356 length 167
(5) EAP-Message =
0x0107006d0d8000000fdbe33d32c6d71228255ebeafdd018899878a8fe0a80f886c4eb5170303004513e76f1ad52ae44957d10ac939c3d9a463013e536725fa716af274ae1ca18448ac2820e8abb26835729ebf1a1e0cca06c6553bba22f0249caa9e90802e54b60b89891323cb
(5) Message-Authenticator = 0x00000000000000000000000000000000
(5) State = 0xa960c76dac67ca67c033c547c41b2362
(5) Finished request
Waking up in 2.9 seconds.
(2) Cleaning up request packet ID 227 with timestamp +1410 due to
cleanup_delay was reached
Ready to process requests
2
2
Hi everybody
Recently, I upgraded from FreeRADIUS 3.2.6 to 3.2.7. I implemented a rest module call to our IPAM (Infoblox) on version 3.2.6. The rest module call is still in «evaluation» and was not used in production, that’s why it is not used frequently and I was not forced to troubleshoot the problem before.
But know I would need some help:
1. the rest module call to our IPAM (Infoblox) was working fine in FR 3.2.6.
2. The goal is to check, if a client with a given mac address (Calling-Station-Id) is listed in a given IP subnet on Infoblox, additionally to the 802.1x (PEAP) authentication on the given SSID
a) if yes (REST-HTTP-Status-Code = 200 and REST-HTTP-Body != []), then return an ACCESS-ACCEPT
b) if not (REST-HTTP-Status-Code != 200 or REST-HTTP-Body == []), then return an ACCESS-REJECT
As I stated above, it worked on FR 3.2.6, but since upgrading to 3.2.7, it SOMETIMES still works, but most of the time it DOESN’T and it returns a HTTP status code of 403 («Forbidden»). Stangely, I never get a HTTP status code of 403 with the exact same user credentials while trying the same API call in Postman:
[
{
"_ref": "record:host/ZG5zLmhvc3QkLl9kZWZhdWx0LmNoLnVuaWJlLnptay5wcnRnLXByb2JlLWZyZWVycmFkaXVzLXpta2Jlcm4:prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default",
"ipv4addrs": [
{
"_ref": "record:host_ipv4addr/ZG5zLmhvc3RfYWRkcmVzcyQuX2RlZmF1bHQuY2gudW5pYmUuem1rLnBydGctcHJvYmUtZnJlZXJyYWRpdXMtem1rYmVybi4xNzIuMjUuOC4xMC4:172.25.8.10/prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default",
"configure_for_dhcp": true,
"host": "prtg-probe-freerradius-zmkbern.zmk.unibe.ch",
"ipv4addr": "172.25.8.10",
"mac": "a1:b2:c3:d4:e5:f6"
}
],
"name": "prtg-probe-freeradius-ssid.domain.xy",
"view": "default"
}
]
Debug output of a working attempt (if you need the whole debug output, please let me know):
(3973) if (Service-Type == Call-Check) {
(3973) if (Service-Type == Call-Check) -> TRUE
(3973) if (Service-Type == Call-Check) {
(3973) switch &Called-Station-SSID {
(3973) case zmkbern-DEV {
(3973) update request {
(3973) &locMacAuth-IP-Subnet := "172.25.8.0/21"
(3973) } # update request = noop
rlm_rest (rest): Reserved connection (38)
(3973) rest: Expanding URI components
(3973) rest: EXPAND https://gridmaster.domain.xy <https://gridmaster.domain.xy/>
(3973) rest: --> https://gridmaster.domain.xy <https://gridmaster.domain.xy/>
(3973) rest: EXPAND /wapi/v2.11.3/record:host?network=%{locMacAuth-IP-Subnet}&mac=%{tolower:%{request:locMacAuth-Calling-Station-Id}}
(3973) rest: --> /wapi/v2.11.3/record:host?network=172.25.8.0%2F21&mac=a1%3Ab2%3Ac3%3Ad4%3Ae5%3Af6
(3973) rest: Sending HTTP GET to https://gridmaster.domain.xy/wapi/v2.11.3/record:host?network=172.25.8.0%2F…
(3973) rest: EXPAND id_svcinfobloxro_prod
(3973) rest: --> id_svcinfobloxro_prod
(3973) rest: EXPAND c1K25E9MyrXLC6fCyZ
(3973) rest: --> c1K25E9MyrXLC6fCyZ
(3973) rest: Processing response header
(3973) rest: Status : 200 (OK)
(3973) rest: Type : json (application/json)
(3973) rest: Adding reply:REST-HTTP-Status-Code = "200"
(3973) rest: Adding reply:REST-HTTP-Body += "[ { "_ref": "record:host/ZG5zLmhvc3QkLl9kZWZhdWx0LmNoLnVuaWJlLnptay5wcnRnLXByb2JlLWZyZWVycmFkaXVzLXpta2Jlcm4:prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default", "ipv4addrs": [ { "_ref": "record:host_ipv4addr/ZG5zLmhvc3RfYWRkcmVzcyQuX2RlZmF1bHQuY2gudW5pYmUuem1rLnBydGctcHJvYmUtZnJlZXJyYWRpdXMtem1rYmVybi4xNzIuMjUuOC4xMC4:172.25.8.10/prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default", "configure_for_dhcp": true, "host": "prtg-probe-freerradius-zmkbern.zmk.unibe.ch", "ipv4addr": "172.25.8.10", "mac": "a1:b2:c3:d4:e5:f6" } ], "name": "prtg-probe-freerradius-zmkbern.zmk.unibe.ch", "view": "default" } ]"
rlm_rest (rest): Released connection (38)
Need more connections to reach 10 spares
rlm_rest (rest): Opening additional connection (40), 1 of 29 pending slots used
rlm_rest (rest): Connecting to https://gridmaster.domain.xy <https://gridmaster.domain.xy/>
rlm_rest (rest): Closing expired connection (39) - Hit idle_timeout limit
rlm_rest (rest): You probably need to lower "min"
rlm_rest (rest): Closing expired connection (35) - Hit idle_timeout limit
(3973) [rest] = updated
(3973) } # case zmkbern-DEV = updated
(3973) } # switch &Called-Station-SSID = updated
(3973) if (reply:REST-HTTP-Status-Code == "200") {
(3973) if (reply:REST-HTTP-Status-Code == "200") -> TRUE
(3973) if (reply:REST-HTTP-Status-Code == "200") {
(3973) if (reply:REST-HTTP-Body == "[]") {
(3973) if (reply:REST-HTTP-Body == "[]") -> FALSE
(3973) else {
(3973) policy accept {
(3973) update control {
(3973) &Response-Packet-Type = Access-Accept
(3973) } # update control = noop
(3973) [handled] = handled
(3973) } # policy accept = handled
(3973) } # else = handled
(3973) } # if (reply:REST-HTTP-Status-Code == "200") = handled
(3973) } # if (Service-Type == Call-Check) = handled
(3973) } # authorize = handled
(3973) # Executing section post-auth from file /etc/freeradius/sites-enabled/default
Debug of a failing attempt:
(4245) if (Service-Type == Call-Check) {
(4245) if (Service-Type == Call-Check) -> TRUE
(4245) if (Service-Type == Call-Check) {
(4245) switch &Called-Station-SSID {
(4245) case zmkbern-DEV {
(4245) update request {
(4245) &locMacAuth-IP-Subnet := "172.25.8.0/21"
(4245) } # update request = noop
rlm_rest (rest): Reserved connection (40)
(4245) rest: Expanding URI components
(4245) rest: EXPAND https://gridmaster.domain.xy <https://gridmaster.domain.xy/>
(4245) rest: --> https://gridmaster.domain.xy <https://gridmaster.domain.xy/>
(4245) rest: EXPAND /wapi/v2.11.3/record:host?network=%{locMacAuth-IP-Subnet}&mac=%{tolower:%{request:locMacAuth-Calling-Station-Id}}
(4245) rest: --> /wapi/v2.11.3/record:host?network=172.25.8.0%2F21&mac=a1%3Ab2%3Ac3%3Ad4%3Ae5%3Af6
(4245) rest: Sending HTTP GET to https://gridmaster.domain.xy/wapi/v2.11.3/record:host?network=172.25.8.0%2F…
(4245) rest: EXPAND id_svcinfobloxro_prod
(4245) rest: --> id_svcinfobloxro_prod
(4245) rest: EXPAND c1K25E9MyrXLC6fCyZ
(4245) rest: --> c1K25E9MyrXLC6fCyZ
(4245) rest: Processing response header
(4245) rest: Status : 403 (Forbidden)
(4245) rest: Type : html (text/html)
(4245) rest: Adding reply:REST-HTTP-Status-Code = "403"
(4245) rest: ERROR: Server returned:
(4245) rest: ERROR: <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
(4245) rest: ERROR: <html><head>
(4245) rest: ERROR: <title>403 Forbidden</title>
(4245) rest: ERROR: </head><body>
(4245) rest: ERROR: <h1>Forbidden</h1>
(4245) rest: ERROR: <p>You don't have permission to access this resource.</p>
(4245) rest: ERROR: </body></html>
rlm_rest (rest): Released connection (40)
Need more connections to reach 10 spares
rlm_rest (rest): Opening additional connection (45), 1 of 26 pending slots used
rlm_rest (rest): Connecting to https://gridmaster.domain.xy <https://gridmaster.domain.xy/>
(4245) [rest] = userlock
(4245) } # case zmkbern-DEV = userlock
(4245) } # switch &Called-Station-SSID = userlock
(4245) } # if (Service-Type == Call-Check) = userlock
(4245) } # authorize = userlock
(4245) Invalid user (Failed retrieving values required to evaluate condition): [<no User-Name attribute>] (from client localhost port 0 cli A1-B2-C3-D4-E5-F6)
(4245) Using Post-Auth-Type Reject
(4245) # Executing group from file /etc/freeradius/sites-enabled/default
(4245) Post-Auth-Type REJECT {
(4245) attr_filter.access_reject: EXPAND %{User-Name}
(4245) attr_filter.access_reject: -->
(4245) [attr_filter.access_reject] = noop
(4245) [eap] = noop
(4245) policy remove_reply_message_if_eap {
(4245) if (&reply:EAP-Message && &reply:Reply-Message) {
(4245) if (&reply:EAP-Message && &reply:Reply-Message) -> FALSE
(4245) else {
(4245) [noop] = noop
(4245) } # else = noop
(4245) } # policy remove_reply_message_if_eap = noop
(4245) if (Service-Type == Call-Check) {
(4245) if (Service-Type == Call-Check) -> TRUE
(4245) if (Service-Type == Call-Check) {
I also get the following debug output from time to time in correspondance to this rest module call:
rlm_rest (rest): Released connection (0)
Need more connections to reach 10 spares
rlm_rest (rest): Opening additional connection (11), 1 of 28 pending slots used
rlm_rest (rest): Connecting to https://gridmaster.domiain.xy <https://gridmaster.domiain.xy/>
rlm_rest (rest): Closing expired connection (10) - Hit idle_timeout limit
rlm_rest (rest): Closing expired connection (9) - Hit idle_timeout limit
rlm_rest (rest): You probably need to lower "min"
rlm_rest (rest): Closing expired connection (8) - Hit idle_timeout limit
(988) [rest] = userlock
(988) } # case zmkbern-DEV = userlock
(988) } # switch &Called-Station-SSID = userlock
(988) } # if (Service-Type == Call-Check) = userlock
(988) } # authorize = userlock
As I don’t really understand the rest / threading part, any help is welcome.
Regards
Dominic
1
0
03 Apr '25
Hi everybody
Recently, I upgraded from FreeRADIUS 3.2.6 to 3.2.7. I implemented a rest module call to our IPAM (Infoblox) on version 3.2.6. The rest module call is still in «evaluation» and was not used in production, that’s why it is not used frequently and I was not forced to troubleshoot the problem before.
But know I would need some help:
1. the rest module call to our IPAM (Infoblox) was working fine in FR 3.2.6.
2. The goal is to check, if a client with a given mac address (Calling-Station-Id) is listed in a given IP subnet on Infoblox, additionally to the 802.1x (PEAP) authentication on the given SSID
a) if yes (REST-HTTP-Status-Code = 200 and REST-HTTP-Body != []), then return an ACCESS-ACCEPT
b) if not (REST-HTTP-Status-Code != 200 or REST-HTTP-Body == []), then return an ACCESS-REJECT
As I stated above, it worked on FR 3.2.6, but since upgrading to 3.2.7, it SOMETIMES still works, but most of the time it DOESN’T and it returns a HTTP status code of 403 («Forbidden»). Stangely, I never get a HTTP status code of 403 with the exact same user credentials while trying the same API call in Postman:
[
{
"_ref": "record:host/ZG5zLmhvc3QkLl9kZWZhdWx0LmNoLnVuaWJlLnptay5wcnRnLXByb2JlLWZyZWVycmFkaXVzLXpta2Jlcm4:prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default",
"ipv4addrs": [
{
"_ref": "record:host_ipv4addr/ZG5zLmhvc3RfYWRkcmVzcyQuX2RlZmF1bHQuY2gudW5pYmUuem1rLnBydGctcHJvYmUtZnJlZXJyYWRpdXMtem1rYmVybi4xNzIuMjUuOC4xMC4:172.25.8.10/prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default",
"configure_for_dhcp": true,
"host": "prtg-probe-freerradius-zmkbern.zmk.unibe.ch",
"ipv4addr": "172.25.8.10",
"mac": "a1:b2:c3:d4:e5:f6"
}
],
"name": "prtg-probe-freeradius-ssid.domain.xy",
"view": "default"
}
]
Debug output of a working attempt (if you need the whole debug output, please let me know):
(3973) if (Service-Type == Call-Check) {
(3973) if (Service-Type == Call-Check) -> TRUE
(3973) if (Service-Type == Call-Check) {
(3973) switch &Called-Station-SSID {
(3973) case zmkbern-DEV {
(3973) update request {
(3973) &locMacAuth-IP-Subnet := "172.25.8.0/21"
(3973) } # update request = noop
rlm_rest (rest): Reserved connection (38)
(3973) rest: Expanding URI components
(3973) rest: EXPAND https://gridmaster.domain.xy
(3973) rest: --> https://gridmaster.domain.xy
(3973) rest: EXPAND /wapi/v2.11.3/record:host?network=%{locMacAuth-IP-Subnet}&mac=%{tolower:%{request:locMacAuth-Calling-Station-Id}}
(3973) rest: --> /wapi/v2.11.3/record:host?network=172.25.8.0%2F21&mac=a1%3Ab2%3Ac3%3Ad4%3Ae5%3Af6
(3973) rest: Sending HTTP GET to https://gridmaster.domain.xy/wapi/v2.11.3/record:host?network=172.25.8.0%2F…
(3973) rest: EXPAND id_svcinfobloxro_prod
(3973) rest: --> id_svcinfobloxro_prod
(3973) rest: EXPAND c1K25E9MyrXLC6fCyZ
(3973) rest: --> c1K25E9MyrXLC6fCyZ
(3973) rest: Processing response header
(3973) rest: Status : 200 (OK)
(3973) rest: Type : json (application/json)
(3973) rest: Adding reply:REST-HTTP-Status-Code = "200"
(3973) rest: Adding reply:REST-HTTP-Body += "[ { "_ref": "record:host/ZG5zLmhvc3QkLl9kZWZhdWx0LmNoLnVuaWJlLnptay5wcnRnLXByb2JlLWZyZWVycmFkaXVzLXpta2Jlcm4:prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default", "ipv4addrs": [ { "_ref": "record:host_ipv4addr/ZG5zLmhvc3RfYWRkcmVzcyQuX2RlZmF1bHQuY2gudW5pYmUuem1rLnBydGctcHJvYmUtZnJlZXJyYWRpdXMtem1rYmVybi4xNzIuMjUuOC4xMC4:172.25.8.10/prtg-probe-freerradius-zmkbern.zmk.unibe.ch/default", "configure_for_dhcp": true, "host": "prtg-probe-freerradius-zmkbern.zmk.unibe.ch", "ipv4addr": "172.25.8.10", "mac": "a1:b2:c3:d4:e5:f6" } ], "name": "prtg-probe-freerradius-zmkbern.zmk.unibe.ch", "view": "default" } ]"
rlm_rest (rest): Released connection (38)
Need more connections to reach 10 spares
rlm_rest (rest): Opening additional connection (40), 1 of 29 pending slots used
rlm_rest (rest): Connecting to https://gridmaster.domain.xy
rlm_rest (rest): Closing expired connection (39) - Hit idle_timeout limit
rlm_rest (rest): You probably need to lower "min"
rlm_rest (rest): Closing expired connection (35) - Hit idle_timeout limit
(3973) [rest] = updated
(3973) } # case zmkbern-DEV = updated
(3973) } # switch &Called-Station-SSID = updated
(3973) if (reply:REST-HTTP-Status-Code == "200") {
(3973) if (reply:REST-HTTP-Status-Code == "200") -> TRUE
(3973) if (reply:REST-HTTP-Status-Code == "200") {
(3973) if (reply:REST-HTTP-Body == "[]") {
(3973) if (reply:REST-HTTP-Body == "[]") -> FALSE
(3973) else {
(3973) policy accept {
(3973) update control {
(3973) &Response-Packet-Type = Access-Accept
(3973) } # update control = noop
(3973) [handled] = handled
(3973) } # policy accept = handled
(3973) } # else = handled
(3973) } # if (reply:REST-HTTP-Status-Code == "200") = handled
(3973) } # if (Service-Type == Call-Check) = handled
(3973) } # authorize = handled
(3973) # Executing section post-auth from file /etc/freeradius/sites-enabled/default
Debug of a failing attempt:
(4245) if (Service-Type == Call-Check) {
(4245) if (Service-Type == Call-Check) -> TRUE
(4245) if (Service-Type == Call-Check) {
(4245) switch &Called-Station-SSID {
(4245) case zmkbern-DEV {
(4245) update request {
(4245) &locMacAuth-IP-Subnet := "172.25.8.0/21"
(4245) } # update request = noop
rlm_rest (rest): Reserved connection (40)
(4245) rest: Expanding URI components
(4245) rest: EXPAND https://gridmaster.domain.xy
(4245) rest: --> https://gridmaster.domain.xy
(4245) rest: EXPAND /wapi/v2.11.3/record:host?network=%{locMacAuth-IP-Subnet}&mac=%{tolower:%{request:locMacAuth-Calling-Station-Id}}
(4245) rest: --> /wapi/v2.11.3/record:host?network=172.25.8.0%2F21&mac=a1%3Ab2%3Ac3%3Ad4%3Ae5%3Af6
(4245) rest: Sending HTTP GET to https://gridmaster.domain.xy/wapi/v2.11.3/record:host?network=172.25.8.0%2F…
(4245) rest: EXPAND id_svcinfobloxro_prod
(4245) rest: --> id_svcinfobloxro_prod
(4245) rest: EXPAND c1K25E9MyrXLC6fCyZ
(4245) rest: --> c1K25E9MyrXLC6fCyZ
(4245) rest: Processing response header
(4245) rest: Status : 403 (Forbidden)
(4245) rest: Type : html (text/html)
(4245) rest: Adding reply:REST-HTTP-Status-Code = "403"
(4245) rest: ERROR: Server returned:
(4245) rest: ERROR: <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
(4245) rest: ERROR: <html><head>
(4245) rest: ERROR: <title>403 Forbidden</title>
(4245) rest: ERROR: </head><body>
(4245) rest: ERROR: <h1>Forbidden</h1>
(4245) rest: ERROR: <p>You don't have permission to access this resource.</p>
(4245) rest: ERROR: </body></html>
rlm_rest (rest): Released connection (40)
Need more connections to reach 10 spares
rlm_rest (rest): Opening additional connection (45), 1 of 26 pending slots used
rlm_rest (rest): Connecting to https://gridmaster.domain.xy
(4245) [rest] = userlock
(4245) } # case zmkbern-DEV = userlock
(4245) } # switch &Called-Station-SSID = userlock
(4245) } # if (Service-Type == Call-Check) = userlock
(4245) } # authorize = userlock
(4245) Invalid user (Failed retrieving values required to evaluate condition): [<no User-Name attribute>] (from client localhost port 0 cli A1-B2-C3-D4-E5-F6)
(4245) Using Post-Auth-Type Reject
(4245) # Executing group from file /etc/freeradius/sites-enabled/default
(4245) Post-Auth-Type REJECT {
(4245) attr_filter.access_reject: EXPAND %{User-Name}
(4245) attr_filter.access_reject: -->
(4245) [attr_filter.access_reject] = noop
(4245) [eap] = noop
(4245) policy remove_reply_message_if_eap {
(4245) if (&reply:EAP-Message && &reply:Reply-Message) {
(4245) if (&reply:EAP-Message && &reply:Reply-Message) -> FALSE
(4245) else {
(4245) [noop] = noop
(4245) } # else = noop
(4245) } # policy remove_reply_message_if_eap = noop
(4245) if (Service-Type == Call-Check) {
(4245) if (Service-Type == Call-Check) -> TRUE
(4245) if (Service-Type == Call-Check) {
I also get the following debug output from time to time in correspondance to this rest module call:
rlm_rest (rest): Released connection (0)
Need more connections to reach 10 spares
rlm_rest (rest): Opening additional connection (11), 1 of 28 pending slots used
rlm_rest (rest): Connecting to https://gridmaster.domiain.xy
rlm_rest (rest): Closing expired connection (10) - Hit idle_timeout limit
rlm_rest (rest): Closing expired connection (9) - Hit idle_timeout limit
rlm_rest (rest): You probably need to lower "min"
rlm_rest (rest): Closing expired connection (8) - Hit idle_timeout limit
(988) [rest] = userlock
(988) } # case zmkbern-DEV = userlock
(988) } # switch &Called-Station-SSID = userlock
(988) } # if (Service-Type == Call-Check) = userlock
(988) } # authorize = userlock
As I don’t really understand the rest / threading part, any help is welcome.
Regards
Dominic
_________________________________
Universität Bern
Abteilung Informatikdienste
Dominic Stalder
Network Engineer
Hochschulstrasse 6
CH-3012 Bern
Tel. +41 (0)31 684 38 18
dominic.stalder(a)unibe.ch<mailto:dominic.stalder@unibe.ch>
www.id.unibe.ch
_________________________________
1
0
We use FreeRADIUS for our wireless authentication. Everything worked
correctly until around November 2024. Now, certain users are unable to
authenticate. The FreeRADIUS server points to our Google Workspace
domain using Google Secure LDAP. Our domain has a primary domain and a
subdomain. Any user in the subdomain is affected by this issue. Below
are the results of the authentication attempt for two users in the
subdomain. These results are taken from running freeradius -X -x. If I
add an alias to any user in the subdomain to point to the primary,
they can log in. I am hoping someone here could give me some ideas on
what it might be because Google says it is not on their end.
Thu Apr 3 08:21:22 2025 : Debug: (8) Received Access-Request Id 12
from 192.168.1.5:60774 to 10.220.3.99:1812 length 81
Thu Apr 3 08:21:22 2025 : Debug: (8) User-Name =
"ben.tennyson(a)student.madeupdomain.com"
Thu Apr 3 08:21:22 2025 : Debug: (8) User-Password = "Omnitrix2024"
Thu Apr 3 08:21:22 2025 : Debug: (8) Framed-IP-Address = 192.168.1.5
Thu Apr 3 08:21:22 2025 : Debug: (8) session-state: No State attribute
Thu Apr 3 08:21:22 2025 : Debug: (8) # Executing section authorize
from file /etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:22 2025 : Debug: (8) authorize {
Thu Apr 3 08:21:22 2025 : Debug: (8) policy filter_username {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name) -> TRUE
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ / /) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ / /) -> FALSE
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ /@[^@]*@/ ) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~
/@[^@]*@/ ) -> FALSE
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ /\.\./ ) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ /\.\./
) -> FALSE
Thu Apr 3 08:21:22 2025 : Debug: (8) if ((&User-Name =~ /@/)
&& (&User-Name !~ /(a)(.+)\.(.+)$/)) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if ((&User-Name =~ /@/)
&& (&User-Name !~ /(a)(.+)\.(.+)$/)) -> FALSE
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ /\.$/) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ /\.$/)
-> FALSE
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ /(a)\./) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (&User-Name =~ /(a)\./)
-> FALSE
Thu Apr 3 08:21:22 2025 : Debug: (8) } # if (&User-Name) = notfound
Thu Apr 3 08:21:22 2025 : Debug: (8) } # policy filter_username = notfound
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling preprocess (rlm_preprocess)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from preprocess (rlm_preprocess)
Thu Apr 3 08:21:22 2025 : Debug: (8) [preprocess] = ok
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling chap (rlm_chap)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from chap (rlm_chap)
Thu Apr 3 08:21:22 2025 : Debug: (8) [chap] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling mschap (rlm_mschap)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from mschap (rlm_mschap)
Thu Apr 3 08:21:22 2025 : Debug: (8) [mschap] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling digest (rlm_digest)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from digest (rlm_digest)
Thu Apr 3 08:21:22 2025 : Debug: (8) [digest] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling suffix (rlm_realm)
Thu Apr 3 08:21:22 2025 : Debug: (8) suffix: Checking for suffix after "@"
Thu Apr 3 08:21:22 2025 : Debug: (8) suffix: Looking up realm
"student.madeupdomain.com" for User-Name =
"ben.tennyson(a)student.madeupdomain.com"
Thu Apr 3 08:21:22 2025 : Debug: (8) suffix: Found realm
"student.madeupdomain.com"
Thu Apr 3 08:21:22 2025 : Debug: (8) suffix: Adding
Stripped-User-Name = "ben.tennyson"
Thu Apr 3 08:21:22 2025 : Debug: (8) suffix: Adding Realm =
"student.madeupdomain.com"
Thu Apr 3 08:21:22 2025 : Debug: (8) suffix: Authentication realm is LOCAL
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from suffix (rlm_realm)
Thu Apr 3 08:21:22 2025 : Debug: (8) [suffix] = ok
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling eap (rlm_eap)
Thu Apr 3 08:21:22 2025 : Debug: (8) eap: No EAP-Message, not doing EAP
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from eap (rlm_eap)
Thu Apr 3 08:21:22 2025 : Debug: (8) [eap] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling files (rlm_files)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from files (rlm_files)
Thu Apr 3 08:21:22 2025 : Debug: (8) [files] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling ldap (rlm_ldap)
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: EXPAND (mail=%{User-Name})
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: -->
(mail=ben.tennyson(a)student.madeupdomain.com)
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Performing search in
"dc=madeupdomain,dc=com" with filter
"(mail=ben.tennyson(a)student.madeupdomain.com)", scope "sub"
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Waiting for search result...
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: User object found at DN
"uid=ben.tennyson,ou=LBHS,ou=Students,ou=Users,dc=madeupdomain,dc=com"
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Processing user attributes
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Attribute "userPassword"
not found in LDAP object
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Attribute
"radiusControlAttribute" not found in LDAP object
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Attribute
"radiusRequestAttribute" not found in LDAP object
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Attribute
"radiusReplyAttribute" not found in LDAP object
Thu Apr 3 08:21:22 2025 : WARNING: (8) ldap: No "known good" password
added. Ensure the admin user has permission to read the password
attribute
Thu Apr 3 08:21:22 2025 : WARNING: (8) ldap: PAP authentication will
*NOT* work with Active Directory (if that is what you were trying to
configure)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from ldap (rlm_ldap)
Thu Apr 3 08:21:22 2025 : Debug: (8) [ldap] = ok
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling expiration (rlm_expiration)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from expiration (rlm_expiration)
Thu Apr 3 08:21:22 2025 : Debug: (8) [expiration] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling logintime (rlm_logintime)
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from logintime (rlm_logintime)
Thu Apr 3 08:21:22 2025 : Debug: (8) [logintime] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
calling pap (rlm_pap)
Thu Apr 3 08:21:22 2025 : WARNING: (8) pap: No "known good" password
found for the user. Not setting Auth-Type
Thu Apr 3 08:21:22 2025 : WARNING: (8) pap: Authentication will fail
unless a "known good" password is available
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authorize]:
returned from pap (rlm_pap)
Thu Apr 3 08:21:22 2025 : Debug: (8) [pap] = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) if (User-Password) {
Thu Apr 3 08:21:22 2025 : Debug: (8) if (User-Password) -> TRUE
Thu Apr 3 08:21:22 2025 : Debug: (8) if (User-Password) {
Thu Apr 3 08:21:22 2025 : Debug: (8) update control {
Thu Apr 3 08:21:22 2025 : Debug: (8) Auth-Type := LDAP
Thu Apr 3 08:21:22 2025 : Debug: (8) } # update control = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) } # if (User-Password) = noop
Thu Apr 3 08:21:22 2025 : Debug: (8) } # authorize = ok
Thu Apr 3 08:21:22 2025 : Debug: (8) Found Auth-Type = LDAP
Thu Apr 3 08:21:22 2025 : Debug: (8) # Executing group from file
/etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:22 2025 : Debug: (8) authenticate {
Thu Apr 3 08:21:22 2025 : Debug: (8) modsingle[authenticate]:
calling ldap (rlm_ldap)
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Login attempt by "ben.tennyson"
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Using user DN from request
"uid=ben.tennyson,ou=LBHS,ou=Students,ou=Users,dc=madeupdomain,dc=com"
Thu Apr 3 08:21:22 2025 : Debug: (8) ldap: Waiting for bind result...
Thu Apr 3 08:21:23 2025 : ERROR: (8) ldap: Bind credentials
incorrect: Invalid credentials
Thu Apr 3 08:21:23 2025 : ERROR: (8) ldap: Server said: Incorrect password.
Thu Apr 3 08:21:23 2025 : Debug: (8) modsingle[authenticate]:
returned from ldap (rlm_ldap)
Thu Apr 3 08:21:23 2025 : Debug: (8) [ldap] = reject
Thu Apr 3 08:21:23 2025 : Debug: (8) } # authenticate = reject
Thu Apr 3 08:21:23 2025 : Debug: (8) Failed to authenticate the user
Thu Apr 3 08:21:23 2025 : Debug: (8) Using Post-Auth-Type Reject
Thu Apr 3 08:21:23 2025 : Debug: (8) # Executing group from file
/etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:23 2025 : Debug: (8) Post-Auth-Type REJECT {
Thu Apr 3 08:21:23 2025 : Debug: (8) modsingle[post-auth]:
calling attr_filter.access_reject (rlm_attr_filter)
Thu Apr 3 08:21:23 2025 : Debug: %{User-Name}
Thu Apr 3 08:21:23 2025 : Debug: Parsed xlat tree:
Thu Apr 3 08:21:23 2025 : Debug: attribute --> User-Name
Thu Apr 3 08:21:23 2025 : Debug: (8) attr_filter.access_reject:
EXPAND %{User-Name}
Thu Apr 3 08:21:23 2025 : Debug: (8) attr_filter.access_reject:
--> ben.tennyson(a)student.madeupdomain.com
Thu Apr 3 08:21:23 2025 : Debug: (8) attr_filter.access_reject:
Matched entry DEFAULT at line 11
Thu Apr 3 08:21:23 2025 : Debug: (8) modsingle[post-auth]:
returned from attr_filter.access_reject (rlm_attr_filter)
Thu Apr 3 08:21:23 2025 : Debug: (8) [attr_filter.access_reject] = updated
Thu Apr 3 08:21:23 2025 : Debug: (8) modsingle[post-auth]:
calling eap (rlm_eap)
Thu Apr 3 08:21:23 2025 : Debug: (8) eap: Request didn't contain an
EAP-Message, not inserting EAP-Failure
Thu Apr 3 08:21:23 2025 : Debug: (8) modsingle[post-auth]:
returned from eap (rlm_eap)
Thu Apr 3 08:21:23 2025 : Debug: (8) [eap] = noop
Thu Apr 3 08:21:23 2025 : Debug: (8) policy remove_reply_message_if_eap {
Thu Apr 3 08:21:23 2025 : Debug: (8) if (&reply:EAP-Message &&
&reply:Reply-Message) {
Thu Apr 3 08:21:23 2025 : Debug: (8) if (&reply:EAP-Message &&
&reply:Reply-Message) -> FALSE
Thu Apr 3 08:21:23 2025 : Debug: (8) else {
Thu Apr 3 08:21:23 2025 : Debug: (8) modsingle[post-auth]:
calling noop (rlm_always)
Thu Apr 3 08:21:23 2025 : Debug: (8) modsingle[post-auth]:
returned from noop (rlm_always)
Thu Apr 3 08:21:23 2025 : Debug: (8) [noop] = noop
Thu Apr 3 08:21:23 2025 : Debug: (8) } # else = noop
Thu Apr 3 08:21:23 2025 : Debug: (8) } # policy
remove_reply_message_if_eap = noop
Thu Apr 3 08:21:23 2025 : Debug: (8) } # Post-Auth-Type REJECT = updated
Thu Apr 3 08:21:23 2025 : Auth: (8) Login incorrect (ldap: Bind
credentials incorrect: Invalid credentials):
[ben.tennyson(a)student.madeupdomain.com/Omnitrix2024] (from client LBSD
port 0)
Thu Apr 3 08:21:23 2025 : Debug: (8) Delaying response for 1.000000 seconds
Thu Apr 3 08:21:35 2025 : Debug: (36) Received Access-Request Id 13
from 192.168.1.5:61800 to 10.220.3.99:1812 length 77
Thu Apr 3 08:21:35 2025 : Debug: (36) User-Name =
"johnny.test(a)student.madeupdomain.com"
Thu Apr 3 08:21:35 2025 : Debug: (36) User-Password = "Dookie2025"
Thu Apr 3 08:21:35 2025 : Debug: (36) Framed-IP-Address = 192.168.1.5
Thu Apr 3 08:21:35 2025 : Debug: (36) session-state: No State attribute
Thu Apr 3 08:21:35 2025 : Debug: (36) # Executing section authorize
from file /etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:35 2025 : Debug: (36) authorize {
Thu Apr 3 08:21:35 2025 : Debug: (36) policy filter_username {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name) -> TRUE
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~ / /) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~ / /) -> FALSE
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~ /@[^@]*@/ ) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~
/@[^@]*@/ ) -> FALSE
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~ /\.\./ ) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~
/\.\./ ) -> FALSE
Thu Apr 3 08:21:35 2025 : Debug: (36) if ((&User-Name =~ /@/)
&& (&User-Name !~ /(a)(.+)\.(.+)$/)) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if ((&User-Name =~ /@/)
&& (&User-Name !~ /(a)(.+)\.(.+)$/)) -> FALSE
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~ /\.$/) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~
/\.$/) -> FALSE
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~ /(a)\./) {
Thu Apr 3 08:21:35 2025 : Debug: (36) if (&User-Name =~
/(a)\./) -> FALSE
Thu Apr 3 08:21:35 2025 : Debug: (36) } # if (&User-Name) = notfound
Thu Apr 3 08:21:35 2025 : Debug: (36) } # policy filter_username = notfound
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling preprocess (rlm_preprocess)
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
returned from preprocess (rlm_preprocess)
Thu Apr 3 08:21:35 2025 : Debug: (36) [preprocess] = ok
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling chap (rlm_chap)
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
returned from chap (rlm_chap)
Thu Apr 3 08:21:35 2025 : Debug: (36) [chap] = noop
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling mschap (rlm_mschap)
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
returned from mschap (rlm_mschap)
Thu Apr 3 08:21:35 2025 : Debug: (36) [mschap] = noop
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling digest (rlm_digest)
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
returned from digest (rlm_digest)
Thu Apr 3 08:21:35 2025 : Debug: (36) [digest] = noop
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling suffix (rlm_realm)
Thu Apr 3 08:21:35 2025 : Debug: (36) suffix: Checking for suffix after "@"
Thu Apr 3 08:21:35 2025 : Debug: (36) suffix: Looking up realm
"student.madeupdomain.com" for User-Name =
"johnny.test(a)student.madeupdomain.com"
Thu Apr 3 08:21:35 2025 : Debug: (36) suffix: Found realm
"student.madeupdomain.com"
Thu Apr 3 08:21:35 2025 : Debug: (36) suffix: Adding
Stripped-User-Name = "johnny.test"
Thu Apr 3 08:21:35 2025 : Debug: (36) suffix: Adding Realm =
"student.madeupdomain.com"
Thu Apr 3 08:21:35 2025 : Debug: (36) suffix: Authentication realm is LOCAL
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
returned from suffix (rlm_realm)
Thu Apr 3 08:21:35 2025 : Debug: (36) [suffix] = ok
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling eap (rlm_eap)
Thu Apr 3 08:21:35 2025 : Debug: (36) eap: No EAP-Message, not doing EAP
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
returned from eap (rlm_eap)
Thu Apr 3 08:21:35 2025 : Debug: (36) [eap] = noop
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling files (rlm_files)
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
returned from files (rlm_files)
Thu Apr 3 08:21:35 2025 : Debug: (36) [files] = noop
Thu Apr 3 08:21:35 2025 : Debug: (36) modsingle[authorize]:
calling ldap (rlm_ldap)
Thu Apr 3 08:21:35 2025 : Debug: (36) ldap: Waiting for bind result...
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Bind successful
Thu Apr 3 08:21:36 2025 : Debug: (mail=%{User-Name})
Thu Apr 3 08:21:36 2025 : Debug: Parsed xlat tree:
Thu Apr 3 08:21:36 2025 : Debug: literal --> (mail=
Thu Apr 3 08:21:36 2025 : Debug: attribute --> User-Name
Thu Apr 3 08:21:36 2025 : Debug: literal --> )
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: EXPAND (mail=%{User-Name})
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: -->
(mail=johnny.test(a)student.madeupdomain.com)
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Performing search in
"dc=madeupdomain,dc=com" with filter
"(mail=johnny.test(a)student.madeupdomain.com)", scope "sub"
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Waiting for search result...
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: User object found at DN
"uid=johnny.test,ou=LBHS,ou=Students,ou=Users,dc=madeupdomain,dc=com"
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Processing user attributes
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Attribute "userPassword"
not found in LDAP object
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Attribute
"radiusControlAttribute" not found in LDAP object
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Attribute
"radiusRequestAttribute" not found in LDAP object
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Attribute
"radiusReplyAttribute" not found in LDAP object
Thu Apr 3 08:21:36 2025 : WARNING: (36) ldap: No "known good"
password added. Ensure the admin user has permission to read the
password attribute
Thu Apr 3 08:21:36 2025 : WARNING: (36) ldap: PAP authentication will
*NOT* work with Active Directory (if that is what you were trying to
configure)
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authorize]:
returned from ldap (rlm_ldap)
Thu Apr 3 08:21:36 2025 : Debug: (36) [ldap] = ok
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authorize]:
calling expiration (rlm_expiration)
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authorize]:
returned from expiration (rlm_expiration)
Thu Apr 3 08:21:36 2025 : Debug: (36) [expiration] = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authorize]:
calling logintime (rlm_logintime)
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authorize]:
returned from logintime (rlm_logintime)
Thu Apr 3 08:21:36 2025 : Debug: (36) [logintime] = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authorize]:
calling pap (rlm_pap)
Thu Apr 3 08:21:36 2025 : WARNING: (36) pap: No "known good" password
found for the user. Not setting Auth-Type
Thu Apr 3 08:21:36 2025 : WARNING: (36) pap: Authentication will fail
unless a "known good" password is available
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authorize]:
returned from pap (rlm_pap)
Thu Apr 3 08:21:36 2025 : Debug: (36) [pap] = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) if (User-Password) {
Thu Apr 3 08:21:36 2025 : Debug: (36) if (User-Password) -> TRUE
Thu Apr 3 08:21:36 2025 : Debug: (36) if (User-Password) {
Thu Apr 3 08:21:36 2025 : Debug: (36) update control {
Thu Apr 3 08:21:36 2025 : Debug: (36) Auth-Type := LDAP
Thu Apr 3 08:21:36 2025 : Debug: (36) } # update control = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) } # if (User-Password) = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) } # authorize = ok
Thu Apr 3 08:21:36 2025 : Debug: (36) Found Auth-Type = LDAP
Thu Apr 3 08:21:36 2025 : Debug: (36) # Executing group from file
/etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:36 2025 : Debug: (36) authenticate {
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authenticate]:
calling ldap (rlm_ldap)
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Login attempt by "johnny.test"
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Using user DN from
request "uid=johnny.test,ou=LBHS,ou=Students,ou=Users,dc=madeupdomain,dc=com"
Thu Apr 3 08:21:36 2025 : Debug: (36) ldap: Waiting for bind result...
Thu Apr 3 08:21:36 2025 : ERROR: (36) ldap: Bind credentials
incorrect: Invalid credentials
Thu Apr 3 08:21:36 2025 : ERROR: (36) ldap: Server said: Incorrect password.
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[authenticate]:
returned from ldap (rlm_ldap)
Thu Apr 3 08:21:36 2025 : Debug: (36) [ldap] = reject
Thu Apr 3 08:21:36 2025 : Debug: (36) } # authenticate = reject
Thu Apr 3 08:21:36 2025 : Debug: (36) Failed to authenticate the user
Thu Apr 3 08:21:36 2025 : Debug: (36) Using Post-Auth-Type Reject
Thu Apr 3 08:21:36 2025 : Debug: (36) # Executing group from file
/etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:36 2025 : Debug: (36) Post-Auth-Type REJECT {
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[post-auth]:
calling attr_filter.access_reject (rlm_attr_filter)
Thu Apr 3 08:21:36 2025 : Debug: %{User-Name}
Thu Apr 3 08:21:36 2025 : Debug: Parsed xlat tree:
Thu Apr 3 08:21:36 2025 : Debug: attribute --> User-Name
Thu Apr 3 08:21:36 2025 : Debug: (36) attr_filter.access_reject:
EXPAND %{User-Name}
Thu Apr 3 08:21:36 2025 : Debug: (36) attr_filter.access_reject:
--> johnny.test(a)student.madeupdomain.com
Thu Apr 3 08:21:36 2025 : Debug: (36) attr_filter.access_reject:
Matched entry DEFAULT at line 11
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[post-auth]:
returned from attr_filter.access_reject (rlm_attr_filter)
Thu Apr 3 08:21:36 2025 : Debug: (36) [attr_filter.access_reject] = updated
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[post-auth]:
calling eap (rlm_eap)
Thu Apr 3 08:21:36 2025 : Debug: (36) eap: Request didn't contain an
EAP-Message, not inserting EAP-Failure
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[post-auth]:
returned from eap (rlm_eap)
Thu Apr 3 08:21:36 2025 : Debug: (36) [eap] = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) policy remove_reply_message_if_eap {
Thu Apr 3 08:21:36 2025 : Debug: (36) if (&reply:EAP-Message &&
&reply:Reply-Message) {
Thu Apr 3 08:21:36 2025 : Debug: (36) if (&reply:EAP-Message &&
&reply:Reply-Message) -> FALSE
Thu Apr 3 08:21:36 2025 : Debug: (36) else {
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[post-auth]:
calling noop (rlm_always)
Thu Apr 3 08:21:36 2025 : Debug: (36) modsingle[post-auth]:
returned from noop (rlm_always)
Thu Apr 3 08:21:36 2025 : Debug: (36) [noop] = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) } # else = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) } # policy
remove_reply_message_if_eap = noop
Thu Apr 3 08:21:36 2025 : Debug: (36) } # Post-Auth-Type REJECT = updated
Thu Apr 3 08:21:36 2025 : Auth: (36) Login incorrect (ldap: Bind
credentials incorrect: Invalid credentials):
[johnny.test(a)student.madeupdomain.com/Dookie2025] (from client LBSD
port 0)
Thu Apr 3 08:21:36 2025 : Debug: (36) Delaying response for 1.000000 seconds
Thu Apr 3 08:21:50 2025 : Debug: (59) Received Access-Request Id 14
from 192.168.1.5:62921 to 10.220.3.99:1812 length 77
Thu Apr 3 08:21:50 2025 : Debug: (59) User-Name =
"johnny.test(a)student.madeupdomain.com"
Thu Apr 3 08:21:50 2025 : Debug: (59) User-Password = "12345678"
Thu Apr 3 08:21:50 2025 : Debug: (59) Framed-IP-Address = 192.168.1.5
Thu Apr 3 08:21:50 2025 : Debug: (59) session-state: No State attribute
Thu Apr 3 08:21:50 2025 : Debug: (59) # Executing section authorize
from file /etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:50 2025 : Debug: (59) authorize {
Thu Apr 3 08:21:50 2025 : Debug: (59) policy filter_username {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name) -> TRUE
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~ / /) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~ / /) -> FALSE
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~ /@[^@]*@/ ) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~
/@[^@]*@/ ) -> FALSE
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~ /\.\./ ) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~
/\.\./ ) -> FALSE
Thu Apr 3 08:21:50 2025 : Debug: (59) if ((&User-Name =~ /@/)
&& (&User-Name !~ /(a)(.+)\.(.+)$/)) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if ((&User-Name =~ /@/)
&& (&User-Name !~ /(a)(.+)\.(.+)$/)) -> FALSE
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~ /\.$/) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~
/\.$/) -> FALSE
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~ /(a)\./) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&User-Name =~
/(a)\./) -> FALSE
Thu Apr 3 08:21:50 2025 : Debug: (59) } # if (&User-Name) = notfound
Thu Apr 3 08:21:50 2025 : Debug: (59) } # policy filter_username = notfound
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling preprocess (rlm_preprocess)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from preprocess (rlm_preprocess)
Thu Apr 3 08:21:50 2025 : Debug: (59) [preprocess] = ok
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling chap (rlm_chap)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from chap (rlm_chap)
Thu Apr 3 08:21:50 2025 : Debug: (59) [chap] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling mschap (rlm_mschap)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from mschap (rlm_mschap)
Thu Apr 3 08:21:50 2025 : Debug: (59) [mschap] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling digest (rlm_digest)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from digest (rlm_digest)
Thu Apr 3 08:21:50 2025 : Debug: (59) [digest] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling suffix (rlm_realm)
Thu Apr 3 08:21:50 2025 : Debug: (59) suffix: Checking for suffix after "@"
Thu Apr 3 08:21:50 2025 : Debug: (59) suffix: Looking up realm
"student.madeupdomain.com" for User-Name =
"johnny.test(a)student.madeupdomain.com"
Thu Apr 3 08:21:50 2025 : Debug: (59) suffix: Found realm
"student.madeupdomain.com"
Thu Apr 3 08:21:50 2025 : Debug: (59) suffix: Adding
Stripped-User-Name = "johnny.test"
Thu Apr 3 08:21:50 2025 : Debug: (59) suffix: Adding Realm =
"student.madeupdomain.com"
Thu Apr 3 08:21:50 2025 : Debug: (59) suffix: Authentication realm is LOCAL
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from suffix (rlm_realm)
Thu Apr 3 08:21:50 2025 : Debug: (59) [suffix] = ok
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling eap (rlm_eap)
Thu Apr 3 08:21:50 2025 : Debug: (59) eap: No EAP-Message, not doing EAP
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from eap (rlm_eap)
Thu Apr 3 08:21:50 2025 : Debug: (59) [eap] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling files (rlm_files)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from files (rlm_files)
Thu Apr 3 08:21:50 2025 : Debug: (59) [files] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling ldap (rlm_ldap)
Thu Apr 3 08:21:50 2025 : Debug: rlm_ldap (ldap): Reserved connection (1)
Thu Apr 3 08:21:50 2025 : Debug: (mail=%{User-Name})
Thu Apr 3 08:21:50 2025 : Debug: Parsed xlat tree:
Thu Apr 3 08:21:50 2025 : Debug: literal --> (mail=
Thu Apr 3 08:21:50 2025 : Debug: attribute --> User-Name
Thu Apr 3 08:21:50 2025 : Debug: literal --> )
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: EXPAND (mail=%{User-Name})
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: -->
(mail=johnny.test(a)student.madeupdomain.com)
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Performing search in
"dc=madeupdomain,dc=com" with filter
"(mail=johnny.test(a)student.madeupdomain.com)", scope "sub"
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Waiting for search result...
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: User object found at DN
"uid=johnny.test,ou=LBHS,ou=Students,ou=Users,dc=madeupdomain,dc=com"
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Processing user attributes
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Attribute "userPassword"
not found in LDAP object
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Attribute
"radiusControlAttribute" not found in LDAP object
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Attribute
"radiusRequestAttribute" not found in LDAP object
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Attribute
"radiusReplyAttribute" not found in LDAP object
Thu Apr 3 08:21:50 2025 : WARNING: (59) ldap: No "known good"
password added. Ensure the admin user has permission to read the
password attribute
Thu Apr 3 08:21:50 2025 : WARNING: (59) ldap: PAP authentication will
*NOT* work with Active Directory (if that is what you were trying to
configure)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from ldap (rlm_ldap)
Thu Apr 3 08:21:50 2025 : Debug: (59) [ldap] = ok
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling expiration (rlm_expiration)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from expiration (rlm_expiration)
Thu Apr 3 08:21:50 2025 : Debug: (59) [expiration] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling logintime (rlm_logintime)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from logintime (rlm_logintime)
Thu Apr 3 08:21:50 2025 : Debug: (59) [logintime] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
calling pap (rlm_pap)
Thu Apr 3 08:21:50 2025 : WARNING: (59) pap: No "known good" password
found for the user. Not setting Auth-Type
Thu Apr 3 08:21:50 2025 : WARNING: (59) pap: Authentication will fail
unless a "known good" password is available
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authorize]:
returned from pap (rlm_pap)
Thu Apr 3 08:21:50 2025 : Debug: (59) [pap] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) if (User-Password) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (User-Password) -> TRUE
Thu Apr 3 08:21:50 2025 : Debug: (59) if (User-Password) {
Thu Apr 3 08:21:50 2025 : Debug: (59) update control {
Thu Apr 3 08:21:50 2025 : Debug: (59) Auth-Type := LDAP
Thu Apr 3 08:21:50 2025 : Debug: (59) } # update control = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) } # if (User-Password) = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) } # authorize = ok
Thu Apr 3 08:21:50 2025 : Debug: (59) Found Auth-Type = LDAP
Thu Apr 3 08:21:50 2025 : Debug: (59) # Executing group from file
/etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:50 2025 : Debug: (59) authenticate {
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authenticate]:
calling ldap (rlm_ldap)
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Login attempt by "johnny.test"
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Using user DN from
request "uid=johnny.test,ou=LBHS,ou=Students,ou=Users,dc=madeupdomain,dc=com"
Thu Apr 3 08:21:50 2025 : Debug: (59) ldap: Waiting for bind result...
Thu Apr 3 08:21:50 2025 : ERROR: (59) ldap: Bind credentials
incorrect: Invalid credentials
Thu Apr 3 08:21:50 2025 : ERROR: (59) ldap: Server said: Incorrect password.
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[authenticate]:
returned from ldap (rlm_ldap)
Thu Apr 3 08:21:50 2025 : Debug: (59) [ldap] = reject
Thu Apr 3 08:21:50 2025 : Debug: (59) } # authenticate = reject
Thu Apr 3 08:21:50 2025 : Debug: (59) Failed to authenticate the user
Thu Apr 3 08:21:50 2025 : Debug: (59) Using Post-Auth-Type Reject
Thu Apr 3 08:21:50 2025 : Debug: (59) # Executing group from file
/etc/freeradius/3.0/sites-enabled/default
Thu Apr 3 08:21:50 2025 : Debug: (59) Post-Auth-Type REJECT {
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[post-auth]:
calling attr_filter.access_reject (rlm_attr_filter)
Thu Apr 3 08:21:50 2025 : Debug: %{User-Name}
Thu Apr 3 08:21:50 2025 : Debug: Parsed xlat tree:
Thu Apr 3 08:21:50 2025 : Debug: attribute --> User-Name
Thu Apr 3 08:21:50 2025 : Debug: (59) attr_filter.access_reject:
EXPAND %{User-Name}
Thu Apr 3 08:21:50 2025 : Debug: (59) attr_filter.access_reject:
--> johnny.test(a)student.madeupdomain.com
Thu Apr 3 08:21:50 2025 : Debug: (59) attr_filter.access_reject:
Matched entry DEFAULT at line 11
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[post-auth]:
returned from attr_filter.access_reject (rlm_attr_filter)
Thu Apr 3 08:21:50 2025 : Debug: (59) [attr_filter.access_reject] = updated
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[post-auth]:
calling eap (rlm_eap)
Thu Apr 3 08:21:50 2025 : Debug: (59) eap: Request didn't contain an
EAP-Message, not inserting EAP-Failure
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[post-auth]:
returned from eap (rlm_eap)
Thu Apr 3 08:21:50 2025 : Debug: (59) [eap] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) policy remove_reply_message_if_eap {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&reply:EAP-Message &&
&reply:Reply-Message) {
Thu Apr 3 08:21:50 2025 : Debug: (59) if (&reply:EAP-Message &&
&reply:Reply-Message) -> FALSE
Thu Apr 3 08:21:50 2025 : Debug: (59) else {
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[post-auth]:
calling noop (rlm_always)
Thu Apr 3 08:21:50 2025 : Debug: (59) modsingle[post-auth]:
returned from noop (rlm_always)
Thu Apr 3 08:21:50 2025 : Debug: (59) [noop] = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) } # else = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) } # policy
remove_reply_message_if_eap = noop
Thu Apr 3 08:21:50 2025 : Debug: (59) } # Post-Auth-Type REJECT = updated
Thu Apr 3 08:21:50 2025 : Auth: (59) Login incorrect (ldap: Bind
credentials incorrect: Invalid credentials):
[johnny.test(a)student.madeupdomain.com/12345678] (from client LBSD port
0)
Thu Apr 3 08:21:50 2025 : Debug: (59) Delaying response for 1.000000 seconds
Wayne Sprouse, CNA,CCNA,CEH,A+
Long Beach School District
Network Administrator
Work Phone: 228-575-4056
--
"The
foregoing electronic message and any files transmitted with it are
confidential and are intended only for the use of the intended recipient
named above. This communication may contain material protected by the
Family Educational Rights and Privacy Act (FERPA). If you are not the
intended recipient, copying, distribution or use of the contents of this
message is strictly prohibited. If you received this electronic
message
in error, please notify us immediately at (228-864-1146**)."**
3
3
Sam.Hutchings(a)berkshire.nhs.uk would like to recall the message, "TLS handshake not completing".
________________________________
This message is intended exclusively for the individuals to whom it is addressed and may contain information that is privileged, or confidential. If you are not the addressee, you must not read, use or disclose the contents of this e-mail. If you receive this e-mail in error, please advise us immediately on 0300 365 2438 and delete the e-mail. Berkshire Healthcare NHS Foundation Trust has taken every reasonable precaution to ensure that any attachment to this e-mail has been swept for viruses. However, the Trust cannot accept liability for any damage sustained as a result of software viruses and would advise that you carry out your own virus checks before opening any attachment.
1
0