ERROR: Failed to create pair - failed to parse time string
Никита Борисенков
bna at sunlink.ru
Tue Feb 4 15:17:14 CET 2020
Hello, everything works for me, but the messages in the debug output
confuse me.
(0) perl: ERROR: Failed to create pair - failed to parse time string
"фев 4 2020 16:46:57 MSK"
(0) perl: ERROR: &request:Event-Timestamp =
$RAD_REQUEST{'Event-Timestamp'} -> 'фев 4 2020 16:46:57 MSK'
I understand that the date string contains non-English letters.
Is this attribute populated by the service itself?
What can I configure to prevent these errors?
FreeRADIUS Version 3.0.17
Copyright (C) 1999-2017 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/3.0/dictionary
including configuration file /etc/freeradius/3.0/radiusd.conf
including files in directory /etc/freeradius/3.0/mods-enabled/
including configuration file /etc/freeradius/3.0/mods-enabled/perl
including configuration file /etc/freeradius/3.0/mods-enabled/mschap
including configuration file /etc/freeradius/3.0/mods-enabled/sql
including configuration file /etc/freeradius/3.0/mods-enabled/pap
including configuration file /etc/freeradius/3.0/mods-enabled/preprocess
including configuration file /etc/freeradius/3.0/mods-enabled/chap
including files in directory /etc/freeradius/3.0/policy.d/
including configuration file /etc/freeradius/3.0/policy.d/control
including configuration file /etc/freeradius/3.0/policy.d/debug
including configuration file /etc/freeradius/3.0/policy.d/operator-name
including configuration file /etc/freeradius/3.0/policy.d/filter
including configuration file /etc/freeradius/3.0/policy.d/cui
including configuration file /etc/freeradius/3.0/policy.d/dhcp
including configuration file /etc/freeradius/3.0/policy.d/eap
including configuration file
/etc/freeradius/3.0/policy.d/moonshot-targeted-ids
including configuration file /etc/freeradius/3.0/policy.d/accounting
including configuration file /etc/freeradius/3.0/policy.d/canonicalization
including configuration file /etc/freeradius/3.0/policy.d/abfab-tr
including files in directory /etc/freeradius/3.0/sites-enabled/
including configuration file
/etc/freeradius/3.0/sites-enabled/control-socket
including configuration file /etc/freeradius/3.0/sites-enabled/abills
main {
security {
user = "freerad"
group = "freerad"
allow_core_dumps = no
}
name = "freeradius"
prefix = "/usr"
localstatedir = "/var"
logdir = "/var/log/freeradius"
run_dir = "/var/run/freeradius"
}
main {
name = "freeradius"
prefix = "/usr"
localstatedir = "/var"
sbindir = "/usr/sbin"
logdir = "/var/log/freeradius"
run_dir = "/var/run/freeradius"
libdir = "/usr/lib/freeradius"
radacctdir = "/var/log/freeradius/radacct"
hostname_lookups = no
max_request_time = 30
cleanup_delay = 5
max_requests = 262144
pidfile = "/var/run/freeradius/freeradius.pid"
checkrad = "/usr/sbin/checkrad"
debug_level = 0
proxy_requests = no
log {
stripped_names = no
auth = yes
auth_badpass = yes
auth_goodpass = yes
colourise = no
msg_denied = "You are already logged in - access denied"
}
resources {
}
security {
max_attributes = 200
reject_delay = 1.000000
status_server = yes
}
}
radiusd: #### Loading Realms and Home Servers ####
radiusd: #### Loading Clients ####
Debugger not attached
# Creating Auth-Type = PAP
# Creating Auth-Type = MS-CHAP
# Creating Auth-Type = Perl
radiusd: #### Instantiating modules ####
modules {
# Loaded module rlm_perl
# Loading module "perl" from file /etc/freeradius/3.0/mods-enabled/perl
perl {
filename = "/usr/abills/libexec/rlm_perl.pl"
func_authorize = "authorize"
func_authenticate = "authenticate"
func_post_auth = "post_auth"
func_accounting = "accounting"
func_preacct = "preacct"
func_checksimul = "checksimul"
func_detach = "detach"
func_xlat = "xlat"
func_pre_proxy = "pre_proxy"
func_post_proxy = "post_proxy"
func_recv_coa = "recv_coa"
func_send_coa = "send_coa"
}
# Loaded module rlm_mschap
# Loading module "mschap" from file
/etc/freeradius/3.0/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
}
# Loaded module rlm_sql
# Loading module "sql" from file /etc/freeradius/3.0/mods-enabled/sql
sql {
driver = "rlm_sql_mysql"
server = "localhost"
port = 0
login = "abills"
password = <<< secret >>>
radius_db = "abills"
read_groups = yes
read_profiles = yes
read_clients = yes
delete_stale_sessions = yes
sql_user_name = ""
default_user_profile = ""
client_query = "SET sql_mode=''; SELECT ip, INET_NTOA(ip),
CONCAT('NAS_', id), nas_type, DECODE(mng_password, '******') FROM nas
GROUP BY ip"
safe_characters =
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
accounting {
reference = ".query"
type {
accounting-on {
}
accounting-off {
}
start {
}
interim-update {
}
stop {
}
}
}
post-auth {
reference = ".query"
}
}
rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked
# Loaded module rlm_pap
# Loading module "pap" from file /etc/freeradius/3.0/mods-enabled/pap
pap {
normalise = yes
}
# Loaded module rlm_preprocess
# Loading module "preprocess" from file
/etc/freeradius/3.0/mods-enabled/preprocess
preprocess {
huntgroups =
"/etc/freeradius/3.0/mods-config/preprocess/huntgroups"
hints = "/etc/freeradius/3.0/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_chap
# Loading module "chap" from file /etc/freeradius/3.0/mods-enabled/chap
instantiate {
}
# Instantiating module "perl" from file
/etc/freeradius/3.0/mods-enabled/perl
# Instantiating module "mschap" from file
/etc/freeradius/3.0/mods-enabled/mschap
rlm_mschap (mschap): using internal authentication
# Instantiating module "sql" from file
/etc/freeradius/3.0/mods-enabled/sql
rlm_sql_mysql: libmysql version: 10.3.18
mysql {
tls {
}
warnings = "auto"
}
rlm_sql (sql): Attempting to connect to database "abills"
rlm_sql (sql): Initialising connection pool
pool {
start = 5
min = 5
max = 10
spare = 3
uses = 0
lifetime = 0
cleanup_interval = 30
idle_timeout = 60
retry_delay = 1
spread = no
}
rlm_sql (sql): Opening additional connection (0), 1 of 10 pending slots used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'abills' on Localhost via UNIX
socket, server version 10.3.18-MariaDB-0+deb10u1, protocol version 10
rlm_sql (sql): Opening additional connection (1), 1 of 9 pending slots used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'abills' on Localhost via UNIX
socket, server version 10.3.18-MariaDB-0+deb10u1, protocol version 10
rlm_sql (sql): Opening additional connection (2), 1 of 8 pending slots used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'abills' on Localhost via UNIX
socket, server version 10.3.18-MariaDB-0+deb10u1, protocol version 10
rlm_sql (sql): Opening additional connection (3), 1 of 7 pending slots used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'abills' on Localhost via UNIX
socket, server version 10.3.18-MariaDB-0+deb10u1, protocol version 10
rlm_sql (sql): Opening additional connection (4), 1 of 6 pending slots used
rlm_sql_mysql: Starting connect to MySQL server
rlm_sql_mysql: Connected to database 'abills' on Localhost via UNIX
socket, server version 10.3.18-MariaDB-0+deb10u1, protocol version 10
rlm_sql (sql): Processing generate_sql_clients
rlm_sql (sql) in generate_sql_clients: query is SET sql_mode=''; SELECT
ip, INET_NTOA(ip), CONCAT('NAS_', id), nas_type, DECODE(mng_password,
'******') FROM nas GROUP BY ip
rlm_sql (sql): Reserved connection (0)
rlm_sql (sql): Executing select query: SET sql_mode=''; SELECT ip,
INET_NTOA(ip), CONCAT('NAS_', id), nas_type, DECODE(mng_password,
'******') FROM nas GROUP BY ip
rlm_sql (sql): Adding client 10.8.0.2 (NAS_6) to global clients list
rlm_sql (10.8.0.2): Client "NAS_6" (sql) added
rlm_sql (sql): Adding client 10.8.0.3 (NAS_7) to global clients list
rlm_sql (10.8.0.3): Client "NAS_7" (sql) added
rlm_sql (sql): Adding client 10.8.0.4 (NAS_8) to global clients list
rlm_sql (10.8.0.4): Client "NAS_8" (sql) added
rlm_sql (sql): Adding client 192.168.1.2 (NAS_9) to global clients list
rlm_sql (192.168.1.2): Client "NAS_9" (sql) added
rlm_sql (sql): Adding client 192.168.1.3 (NAS_4) to global clients list
rlm_sql (192.168.1.3): Client "NAS_4" (sql) added
rlm_sql (sql): Adding client 192.168.1.4 (NAS_1) to global clients list
rlm_sql (192.168.1.4): Client "NAS_1" (sql) added
rlm_sql (sql): Released connection (0)
# Instantiating module "pap" from file
/etc/freeradius/3.0/mods-enabled/pap
# Instantiating module "preprocess" from file
/etc/freeradius/3.0/mods-enabled/preprocess
reading pairlist file /etc/freeradius/3.0/mods-config/preprocess/huntgroups
reading pairlist file /etc/freeradius/3.0/mods-config/preprocess/hints
} # modules
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/freeradius/3.0/radiusd.conf
} # server
server default { # from file /etc/freeradius/3.0/sites-enabled/abills
# Loading authenticate {...}
# Loading authorize {...}
# Loading preacct {...}
# Loading accounting {...}
# Loading post-auth {...}
} # server default
radiusd: #### Opening IP addresses and Ports ####
listen {
type = "control"
listen {
socket = "/var/run/freeradius/freeradius.sock"
peercred = yes
}
}
listen {
type = "auth"
ipaddr = *
port = 0
}
listen {
type = "acct"
ipaddr = *
port = 0
}
Listening on command file /var/run/freeradius/freeradius.sock
Listening on auth address * port 1812 bound to server default
Listening on acct address * port 1813 bound to server default
Ready to process requests
(0) Received Accounting-Request Id 107 from 192.168.1.2:59720 to
62.109.17.189:1813 length 164
(0) Acct-Session-Id = "5E39757A55C400"
(0) User-Name = "bna-test"
(0) Acct-Status-Type = Stop
(0) Service-Type = Framed-User
(0) Framed-Protocol = PPP
(0) Acct-Authentic = RADIUS
(0) Acct-Session-Time = 87
(0) Acct-Output-Octets = 0
(0) Acct-Input-Octets = 0
(0) Acct-Output-Packets = 0
(0) Acct-Input-Packets = 0
(0) Calling-Station-Id = "2E:8E:B0:85:1F:9C"
(0) NAS-Port-Type = Async
(0) Acct-Terminate-Cause = User-Request
(0) Framed-IP-Address = 10.67.15.11
(0) NAS-Identifier = "shatsk-server"
(0) NAS-Port = 365
(0) Acct-Delay-Time = 0
(0) # Executing section preacct from file
/etc/freeradius/3.0/sites-enabled/abills
(0) preacct {
(0) [preprocess] = ok
(0) } # preacct = ok
(0) # Executing section accounting from file
/etc/freeradius/3.0/sites-enabled/abills
(0) accounting {
(0) perl: $RAD_REQUEST{'User-Name'} = &request:User-Name -> 'bna-test'
(0) perl: $RAD_REQUEST{'NAS-IP-Address'} = &request:NAS-IP-Address ->
'192.168.1.2'
(0) perl: $RAD_REQUEST{'NAS-Port'} = &request:NAS-Port -> '365'
(0) perl: $RAD_REQUEST{'Service-Type'} = &request:Service-Type ->
'Framed-User'
(0) perl: $RAD_REQUEST{'Framed-Protocol'} = &request:Framed-Protocol
-> 'PPP'
(0) perl: $RAD_REQUEST{'Framed-IP-Address'} =
&request:Framed-IP-Address -> '10.67.15.11'
(0) perl: $RAD_REQUEST{'Calling-Station-Id'} =
&request:Calling-Station-Id -> '2E:8E:B0:85:1F:9C'
(0) perl: $RAD_REQUEST{'NAS-Identifier'} = &request:NAS-Identifier ->
'shatsk-server'
(0) perl: $RAD_REQUEST{'NAS-Port-Type'} = &request:NAS-Port-Type ->
'Async'
(0) perl: $RAD_REQUEST{'Acct-Status-Type'} = &request:Acct-Status-Type
-> 'Stop'
(0) perl: $RAD_REQUEST{'Acct-Delay-Time'} = &request:Acct-Delay-Time
-> '0'
(0) perl: $RAD_REQUEST{'Acct-Input-Octets'} =
&request:Acct-Input-Octets -> '0'
(0) perl: $RAD_REQUEST{'Acct-Output-Octets'} =
&request:Acct-Output-Octets -> '0'
(0) perl: $RAD_REQUEST{'Acct-Session-Id'} = &request:Acct-Session-Id
-> '5E39757A55C400'
(0) perl: $RAD_REQUEST{'Acct-Authentic'} = &request:Acct-Authentic ->
'RADIUS'
(0) perl: $RAD_REQUEST{'Acct-Session-Time'} =
&request:Acct-Session-Time -> '87'
(0) perl: $RAD_REQUEST{'Acct-Input-Packets'} =
&request:Acct-Input-Packets -> '0'
(0) perl: $RAD_REQUEST{'Acct-Output-Packets'} =
&request:Acct-Output-Packets -> '0'
(0) perl: $RAD_REQUEST{'Acct-Terminate-Cause'} =
&request:Acct-Terminate-Cause -> 'User-Request'
(0) perl: $RAD_REQUEST{'Event-Timestamp'} = &request:Event-Timestamp
-> 'фев 4 2020 16:46:57 MSK'
(0) perl: &request:Framed-Protocol = $RAD_REQUEST{'Framed-Protocol'} ->
'PPP'
(0) perl: &request:OUTBYTE = $RAD_REQUEST{'OUTBYTE'} -> '0'
(0) perl: &request:Acct-Output-Packets =
$RAD_REQUEST{'Acct-Output-Packets'} -> '0'
(0) perl: &request:OUTBYTE2 = $RAD_REQUEST{'OUTBYTE2'} -> '0'
(0) perl: &request:Service-Type = $RAD_REQUEST{'Service-Type'} ->
'Framed-User'
(0) perl: &request:Acct-Delay-Time = $RAD_REQUEST{'Acct-Delay-Time'} -> '0'
(0) perl: &request:Acct-Output-Gigawords =
$RAD_REQUEST{'Acct-Output-Gigawords'} -> '0'
(0) perl: &request:Acct-Session-Time = $RAD_REQUEST{'Acct-Session-Time'}
-> '87'
(0) perl: &request:NAS-Identifier = $RAD_REQUEST{'NAS-Identifier'} ->
'shatsk-server'
(0) perl: &request:NAS-Port = $RAD_REQUEST{'NAS-Port'} -> '365'
(0) perl: &request:INBYTE2 = $RAD_REQUEST{'INBYTE2'} -> '0'
(0) perl: &request:INTERIUM_INBYTE = $RAD_REQUEST{'INTERIUM_INBYTE'} -> '0'
(0) perl: &request:Acct-Status-Type = $RAD_REQUEST{'Acct-Status-Type'}
-> 'Stop'
(0) perl: &request:Acct-Output-Octets =
$RAD_REQUEST{'Acct-Output-Octets'} -> '0'
(0) perl: &request:NAS-Port-Type = $RAD_REQUEST{'NAS-Port-Type'} -> 'Async'
(0) perl: &request:Acct-Input-Packets =
$RAD_REQUEST{'Acct-Input-Packets'} -> '0'
(0) perl: &request:INBYTE = $RAD_REQUEST{'INBYTE'} -> '0'
(0) perl: &request:Acct-Input-Octets = $RAD_REQUEST{'Acct-Input-Octets'}
-> '0'
(0) perl: &request:NAS-IP-Address = $RAD_REQUEST{'NAS-IP-Address'} ->
'192.168.1.2'
(0) perl: ERROR: Failed to create pair - failed to parse time string
"фев 4 2020 16:46:57 MSK"
(0) perl: ERROR: &request:Event-Timestamp =
$RAD_REQUEST{'Event-Timestamp'} -> 'фев 4 2020 16:46:57 MSK'
(0) perl: &request:Calling-Station-Id =
$RAD_REQUEST{'Calling-Station-Id'} -> '2E:8E:B0:85:1F:9C'
(0) perl: &request:Acct-Authentic = $RAD_REQUEST{'Acct-Authentic'} ->
'RADIUS'
(0) perl: &request:Framed-IP-Address = $RAD_REQUEST{'Framed-IP-Address'}
-> '10.67.15.11'
(0) perl: &request:INTERIUM_OUTBYTE2 = $RAD_REQUEST{'INTERIUM_OUTBYTE2'}
-> '0'
(0) perl: &request:Acct-Input-Gigawords =
$RAD_REQUEST{'Acct-Input-Gigawords'} -> '0'
(0) perl: &request:Acct-Terminate-Cause =
$RAD_REQUEST{'Acct-Terminate-Cause'} -> '1'
(0) perl: &request:Connect-Info = $RAD_REQUEST{'Connect-Info'} -> ''
(0) perl: &request:Acct-Session-Id = $RAD_REQUEST{'Acct-Session-Id'} ->
'5E39757A55C400'
(0) perl: &request:User-Name = $RAD_REQUEST{'User-Name'} -> 'bna-test'
(0) perl: &request:INTERIUM_INBYTE2 = $RAD_REQUEST{'INTERIUM_INBYTE2'}
-> '0'
(0) perl: &request:INTERIUM_OUTBYTE = $RAD_REQUEST{'INTERIUM_OUTBYTE'}
-> '0'
(0) [perl] = ok
(0) } # accounting = ok
(0) Sent Accounting-Response Id 107 from 62.109.17.189:1813 to
192.168.1.2:59720 length 0
(0) Finished request
(0) Cleaning up request packet ID 107 with timestamp +6
Ready to process requests
(1) Received Access-Request Id 110 from 192.168.1.2:35706 to
62.109.17.189:1812 length 164
(1) Service-Type = Framed-User
(1) Framed-Protocol = PPP
(1) User-Name = "bna-test"
(1) MS-CHAP-Challenge = 0x250079a903f6fdb042702a7fcf7b41de
(1) MS-CHAP2-Response =
0x92005b396472ca00be8c9d98052d085b190f0000000000000000c22d93428f3bee0caf1819dc34561f6a9f89d2b9604a0d2c
(1) Calling-Station-Id = "2E:8E:B0:85:1F:9C"
(1) NAS-Identifier = "shatsk-server"
(1) NAS-Port = 354
(1) # Executing section authorize from file
/etc/freeradius/3.0/sites-enabled/abills
(1) authorize {
(1) [preprocess] = ok
(1) mschap: Found MS-CHAP attributes. Setting 'Auth-Type = mschap'
(1) [mschap] = ok
(1) perl: $RAD_REQUEST{'User-Name'} = &request:User-Name -> 'bna-test'
(1) perl: $RAD_REQUEST{'NAS-IP-Address'} = &request:NAS-IP-Address ->
'192.168.1.2'
(1) perl: $RAD_REQUEST{'NAS-Port'} = &request:NAS-Port -> '354'
(1) perl: $RAD_REQUEST{'Service-Type'} = &request:Service-Type ->
'Framed-User'
(1) perl: $RAD_REQUEST{'Framed-Protocol'} = &request:Framed-Protocol
-> 'PPP'
(1) perl: $RAD_REQUEST{'Calling-Station-Id'} =
&request:Calling-Station-Id -> '2E:8E:B0:85:1F:9C'
(1) perl: $RAD_REQUEST{'NAS-Identifier'} = &request:NAS-Identifier ->
'shatsk-server'
(1) perl: $RAD_REQUEST{'Event-Timestamp'} = &request:Event-Timestamp
-> 'фев 4 2020 16:47:01 MSK'
(1) perl: $RAD_REQUEST{'MS-CHAP-Challenge'} =
&request:MS-CHAP-Challenge -> '0x250079a903f6fdb042702a7fcf7b41de'
(1) perl: $RAD_REQUEST{'MS-CHAP2-Response'} =
&request:MS-CHAP2-Response ->
'0x92005b396472ca00be8c9d98052d085b190f0000000000000000c22d93428f3bee0caf1819dc34561f6a9f89d2b9604a0d2c'
(1) perl: $RAD_CHECK{'Auth-Type'} = &control:Auth-Type -> 'MS-CHAP'
(1) perl: $RAD_CONFIG{'Auth-Type'} = &control:Auth-Type -> 'MS-CHAP'
(1) perl: &request:Calling-Station-Id =
$RAD_REQUEST{'Calling-Station-Id'} -> '2E:8E:B0:85:1F:9C'
(1) perl: &request:Framed-Protocol = $RAD_REQUEST{'Framed-Protocol'} ->
'PPP'
(1) perl: &request:Service-Type = $RAD_REQUEST{'Service-Type'} ->
'Framed-User'
(1) perl: &request:MS-CHAP2-Response = $RAD_REQUEST{'MS-CHAP2-Response'}
->
'0x92005b396472ca00be8c9d98052d085b190f0000000000000000c22d93428f3bee0caf1819dc34561f6a9f89d2b9604a0d2c'
(1) perl: &request:NAS-IP-Address = $RAD_REQUEST{'NAS-IP-Address'} ->
'192.168.1.2'
(1) perl: &request:User-Name = $RAD_REQUEST{'User-Name'} -> 'bna-test'
(1) perl: &request:NAS-Identifier = $RAD_REQUEST{'NAS-Identifier'} ->
'shatsk-server'
(1) perl: &request:NAS-Port = $RAD_REQUEST{'NAS-Port'} -> '354'
(1) perl: &request:MS-CHAP-Challenge = $RAD_REQUEST{'MS-CHAP-Challenge'}
-> '0x250079a903f6fdb042702a7fcf7b41de'
(1) perl: ERROR: Failed to create pair - failed to parse time string
"фев 4 2020 16:47:01 MSK"
(1) perl: ERROR: &request:Event-Timestamp =
$RAD_REQUEST{'Event-Timestamp'} -> 'фев 4 2020 16:47:01 MSK'
(1) perl: &control:Cleartext-Password = $RAD_CHECK{'Cleartext-Password'}
-> '********'
(1) perl: &control:Auth-Type = $RAD_CHECK{'Auth-Type'} -> 'MS-CHAP'
(1) [perl] = ok
(1) } # authorize = ok
(1) Found Auth-Type = MS-CHAP
(1) # Executing group from file /etc/freeradius/3.0/sites-enabled/abills
(1) Auth-Type MS-CHAP {
(1) mschap: Found Cleartext-Password, hashing to create NT-Password
(1) mschap: Found Cleartext-Password, hashing to create LM-Password
(1) mschap: Creating challenge hash with username: bna-test
(1) mschap: Client is using MS-CHAPv2
(1) mschap: Adding MS-CHAPv2 MPPE keys
(1) [mschap] = ok
(1) } # Auth-Type MS-CHAP = ok
(1) # Executing section post-auth from file
/etc/freeradius/3.0/sites-enabled/abills
(1) post-auth {
(1) perl: $RAD_REQUEST{'User-Name'} = &request:User-Name -> 'bna-test'
(1) perl: $RAD_REQUEST{'NAS-IP-Address'} = &request:NAS-IP-Address ->
'192.168.1.2'
(1) perl: $RAD_REQUEST{'NAS-Port'} = &request:NAS-Port -> '354'
(1) perl: $RAD_REQUEST{'Service-Type'} = &request:Service-Type ->
'Framed-User'
(1) perl: $RAD_REQUEST{'Framed-Protocol'} = &request:Framed-Protocol
-> 'PPP'
(1) perl: $RAD_REQUEST{'Calling-Station-Id'} =
&request:Calling-Station-Id -> '2E:8E:B0:85:1F:9C'
(1) perl: $RAD_REQUEST{'NAS-Identifier'} = &request:NAS-Identifier ->
'shatsk-server'
(1) perl: $RAD_REQUEST{'Event-Timestamp'} = &request:Event-Timestamp
-> 'янв 1 1970 03:00:00 MSK'
(1) perl: $RAD_REQUEST{'MS-CHAP-Challenge'} =
&request:MS-CHAP-Challenge -> '0x250079a903f6fdb042702a7fcf7b41de'
(1) perl: $RAD_REQUEST{'MS-CHAP2-Response'} =
&request:MS-CHAP2-Response ->
'0x92005b396472ca00be8c9d98052d085b190f0000000000000000c22d93428f3bee0caf1819dc34561f6a9f89d2b9604a0d2c'
(1) perl: $RAD_REPLY{'MS-MPPE-Encryption-Policy'} =
&reply:MS-MPPE-Encryption-Policy -> 'Encryption-Allowed'
(1) perl: $RAD_REPLY{'MS-MPPE-Encryption-Types'} =
&reply:MS-MPPE-Encryption-Types -> 'RC4-40or128-bit-Allowed'
(1) perl: $RAD_REPLY{'MS-MPPE-Send-Key'} = &reply:MS-MPPE-Send-Key ->
'0x77a85433b9d374eb3be839925778140e'
(1) perl: $RAD_REPLY{'MS-MPPE-Recv-Key'} = &reply:MS-MPPE-Recv-Key ->
'0x9e8ff9f5ba2c8bae072bbf86ecfca77f'
(1) perl: $RAD_REPLY{'MS-CHAP2-Success'} = &reply:MS-CHAP2-Success ->
'0x92533d45463644433939323744354344353046363432303744303535383330363444314438304241433042'
(1) perl: $RAD_CHECK{'Auth-Type'} = &control:Auth-Type -> 'MS-CHAP'
(1) perl: $RAD_CHECK{'LM-Password'} = &control:LM-Password ->
'0x0b2de211d6157ab01104594f8c2ef12b'
(1) perl: $RAD_CHECK{'NT-Password'} = &control:NT-Password ->
'0xd28f6c50f12c6793b10bad32a40e7c23'
(1) perl: $RAD_CHECK{'Cleartext-Password'} =
&control:Cleartext-Password -> '********'
(1) perl: $RAD_CONFIG{'Auth-Type'} = &control:Auth-Type -> 'MS-CHAP'
(1) perl: $RAD_CONFIG{'LM-Password'} = &control:LM-Password ->
'0x0b2de211d6157ab01104594f8c2ef12b'
(1) perl: $RAD_CONFIG{'NT-Password'} = &control:NT-Password ->
'0xd28f6c50f12c6793b10bad32a40e7c23'
(1) perl: $RAD_CONFIG{'Cleartext-Password'} =
&control:Cleartext-Password -> '********'
(1) perl: &request:Calling-Station-Id =
$RAD_REQUEST{'Calling-Station-Id'} -> '2E:8E:B0:85:1F:9C'
(1) perl: &request:Framed-Protocol = $RAD_REQUEST{'Framed-Protocol'} ->
'PPP'
(1) perl: &request:Service-Type = $RAD_REQUEST{'Service-Type'} ->
'Framed-User'
(1) perl: &request:MS-CHAP2-Response = $RAD_REQUEST{'MS-CHAP2-Response'}
->
'0x92005b396472ca00be8c9d98052d085b190f0000000000000000c22d93428f3bee0caf1819dc34561f6a9f89d2b9604a0d2c'
(1) perl: &request:NAS-IP-Address = $RAD_REQUEST{'NAS-IP-Address'} ->
'192.168.1.2'
(1) perl: &request:User-Name = $RAD_REQUEST{'User-Name'} -> 'bna-test'
(1) perl: &request:NAS-Identifier = $RAD_REQUEST{'NAS-Identifier'} ->
'shatsk-server'
(1) perl: &request:NAS-Port = $RAD_REQUEST{'NAS-Port'} -> '354'
(1) perl: &request:MS-CHAP-Challenge = $RAD_REQUEST{'MS-CHAP-Challenge'}
-> '0x250079a903f6fdb042702a7fcf7b41de'
(1) perl: ERROR: Failed to create pair - failed to parse time string
"янв 1 1970 03:00:00 MSK"
(1) perl: ERROR: &request:Event-Timestamp =
$RAD_REQUEST{'Event-Timestamp'} -> 'янв 1 1970 03:00:00 MSK'
(1) perl: &reply:PPPD-Downstream-Speed-Limit =
$RAD_REPLY{'PPPD-Downstream-Speed-Limit'} -> '8192'
(1) perl: &reply:MS-MPPE-Encryption-Types =
$RAD_REPLY{'MS-MPPE-Encryption-Types'} -> 'RC4-40or128-bit-Allowed'
(1) perl: &reply:MS-CHAP2-Success = $RAD_REPLY{'MS-CHAP2-Success'} ->
'0x92533d45463644433939323744354344353046363432303744303535383330363444314438304241433042'
(1) perl: &reply:MS-MPPE-Send-Key = $RAD_REPLY{'MS-MPPE-Send-Key'} ->
'0x77a85433b9d374eb3be839925778140e'
(1) perl: &reply:Framed-IP-Address = $RAD_REPLY{'Framed-IP-Address'} ->
'10.67.15.11'
(1) perl: &reply:MS-MPPE-Encryption-Policy =
$RAD_REPLY{'MS-MPPE-Encryption-Policy'} -> 'Encryption-Allowed'
(1) perl: &reply:MS-MPPE-Recv-Key = $RAD_REPLY{'MS-MPPE-Recv-Key'} ->
'0x9e8ff9f5ba2c8bae072bbf86ecfca77f'
(1) perl: &reply:Acct-Interim-Interval =
$RAD_REPLY{'Acct-Interim-Interval'} -> '60'
(1) perl: &reply:User-Name = $RAD_REPLY{'User-Name'} -> 'bna-test'
(1) perl: &reply:PPPD-Upstream-Speed-Limit =
$RAD_REPLY{'PPPD-Upstream-Speed-Limit'} -> '8192'
(1) perl: &reply:Framed-IP-Netmask = $RAD_REPLY{'Framed-IP-Netmask'} ->
'255.255.255.255'
(1) perl: &reply:PPPD-Abills-UID = $RAD_REPLY{'PPPD-Abills-UID'} -> '1319'
(1) perl: &control:LM-Password = $RAD_CHECK{'LM-Password'} ->
'0x0b2de211d6157ab01104594f8c2ef12b'
(1) perl: &control:Cleartext-Password = $RAD_CHECK{'Cleartext-Password'}
-> '********'
(1) perl: &control:Auth-Type = $RAD_CHECK{'Auth-Type'} -> 'MS-CHAP'
(1) perl: &control:NT-Password = $RAD_CHECK{'NT-Password'} ->
'0xd28f6c50f12c6793b10bad32a40e7c23'
(1) [perl] = ok
(1) } # post-auth = ok
(1) Login OK: [bna-test/<via Auth-Type = MS-CHAP>] (from client NAS_9
port 354 cli 2E:8E:B0:85:1F:9C)
(1) Sent Access-Accept Id 110 from 62.109.17.189:1812 to
192.168.1.2:35706 length 0
(1) PPPD-Downstream-Speed-Limit = 8192
(1) MS-MPPE-Encryption-Types = RC4-40or128-bit-Allowed
(1) MS-CHAP2-Success =
0x92533d45463644433939323744354344353046363432303744303535383330363444314438304241433042
(1) MS-MPPE-Send-Key = 0x77a85433b9d374eb3be839925778140e
(1) Framed-IP-Address = 10.67.15.11
(1) MS-MPPE-Encryption-Policy = Encryption-Allowed
(1) MS-MPPE-Recv-Key = 0x9e8ff9f5ba2c8bae072bbf86ecfca77f
(1) Acct-Interim-Interval = 60
(1) User-Name = "bna-test"
(1) PPPD-Upstream-Speed-Limit = 8192
(1) Framed-IP-Netmask = 255.255.255.255
(1) PPPD-Abills-UID = 1319
(1) Finished request
Waking up in 4.9 seconds.
(2) Received Accounting-Request Id 111 from 192.168.1.2:33964 to
62.109.17.189:1813 length 128
(2) Acct-Session-Id = "5E3975D556A200"
(2) User-Name = "bna-test"
(2) Acct-Status-Type = Start
(2) Service-Type = Framed-User
(2) Framed-Protocol = PPP
(2) Calling-Station-Id = "2E:8E:B0:85:1F:9C"
(2) Acct-Authentic = RADIUS
(2) NAS-Port-Type = Async
(2) Framed-IP-Address = 10.67.15.11
(2) NAS-Identifier = "shatsk-server"
(2) NAS-Port = 354
(2) Acct-Delay-Time = 0
(2) # Executing section preacct from file
/etc/freeradius/3.0/sites-enabled/abills
(2) preacct {
(2) [preprocess] = ok
(2) } # preacct = ok
(2) # Executing section accounting from file
/etc/freeradius/3.0/sites-enabled/abills
(2) accounting {
(2) perl: $RAD_REQUEST{'User-Name'} = &request:User-Name -> 'bna-test'
(2) perl: $RAD_REQUEST{'NAS-IP-Address'} = &request:NAS-IP-Address ->
'192.168.1.2'
(2) perl: $RAD_REQUEST{'NAS-Port'} = &request:NAS-Port -> '354'
(2) perl: $RAD_REQUEST{'Service-Type'} = &request:Service-Type ->
'Framed-User'
(2) perl: $RAD_REQUEST{'Framed-Protocol'} = &request:Framed-Protocol
-> 'PPP'
(2) perl: $RAD_REQUEST{'Framed-IP-Address'} =
&request:Framed-IP-Address -> '10.67.15.11'
(2) perl: $RAD_REQUEST{'Calling-Station-Id'} =
&request:Calling-Station-Id -> '2E:8E:B0:85:1F:9C'
(2) perl: $RAD_REQUEST{'NAS-Identifier'} = &request:NAS-Identifier ->
'shatsk-server'
(2) perl: $RAD_REQUEST{'NAS-Port-Type'} = &request:NAS-Port-Type ->
'Async'
(2) perl: $RAD_REQUEST{'Acct-Status-Type'} = &request:Acct-Status-Type
-> 'Start'
(2) perl: $RAD_REQUEST{'Acct-Delay-Time'} = &request:Acct-Delay-Time
-> '0'
(2) perl: $RAD_REQUEST{'Acct-Session-Id'} = &request:Acct-Session-Id
-> '5E3975D556A200'
(2) perl: $RAD_REQUEST{'Acct-Authentic'} = &request:Acct-Authentic ->
'RADIUS'
(2) perl: $RAD_REQUEST{'Event-Timestamp'} = &request:Event-Timestamp
-> 'фев 4 2020 16:47:01 MSK'
(2) perl: &request:Framed-Protocol = $RAD_REQUEST{'Framed-Protocol'} ->
'PPP'
(2) perl: &request:OUTBYTE = $RAD_REQUEST{'OUTBYTE'} -> '0'
(2) perl: &request:OUTBYTE2 = $RAD_REQUEST{'OUTBYTE2'} -> '0'
(2) perl: &request:Service-Type = $RAD_REQUEST{'Service-Type'} ->
'Framed-User'
(2) perl: &request:Acct-Delay-Time = $RAD_REQUEST{'Acct-Delay-Time'} -> '0'
(2) perl: &request:Acct-Output-Gigawords =
$RAD_REQUEST{'Acct-Output-Gigawords'} -> '0'
(2) perl: &request:Acct-Session-Time = $RAD_REQUEST{'Acct-Session-Time'}
-> '0'
(2) perl: &request:NAS-Identifier = $RAD_REQUEST{'NAS-Identifier'} ->
'shatsk-server'
(2) perl: &request:NAS-Port = $RAD_REQUEST{'NAS-Port'} -> '354'
(2) perl: &request:INBYTE2 = $RAD_REQUEST{'INBYTE2'} -> '0'
(2) perl: &request:INTERIUM_INBYTE = $RAD_REQUEST{'INTERIUM_INBYTE'} -> '0'
(2) perl: &request:Acct-Status-Type = $RAD_REQUEST{'Acct-Status-Type'}
-> 'Start'
(2) perl: &request:NAS-Port-Type = $RAD_REQUEST{'NAS-Port-Type'} -> 'Async'
(2) perl: &request:INBYTE = $RAD_REQUEST{'INBYTE'} -> '0'
(2) perl: &request:NAS-IP-Address = $RAD_REQUEST{'NAS-IP-Address'} ->
'192.168.1.2'
(2) perl: ERROR: Failed to create pair - failed to parse time string
"фев 4 2020 16:47:01 MSK"
(2) perl: ERROR: &request:Event-Timestamp =
$RAD_REQUEST{'Event-Timestamp'} -> 'фев 4 2020 16:47:01 MSK'
(2) perl: &request:Acct-Authentic = $RAD_REQUEST{'Acct-Authentic'} ->
'RADIUS'
(2) perl: &request:Calling-Station-Id =
$RAD_REQUEST{'Calling-Station-Id'} -> '2E:8E:B0:85:1F:9C'
(2) perl: &request:Framed-IP-Address = $RAD_REQUEST{'Framed-IP-Address'}
-> '10.67.15.11'
(2) perl: &request:INTERIUM_OUTBYTE2 = $RAD_REQUEST{'INTERIUM_OUTBYTE2'}
-> '0'
(2) perl: &request:Acct-Input-Gigawords =
$RAD_REQUEST{'Acct-Input-Gigawords'} -> '0'
(2) perl: &request:Acct-Session-Id = $RAD_REQUEST{'Acct-Session-Id'} ->
'5E3975D556A200'
(2) perl: &request:Connect-Info = $RAD_REQUEST{'Connect-Info'} -> ''
(2) perl: &request:User-Name = $RAD_REQUEST{'User-Name'} -> 'bna-test'
(2) perl: &request:INTERIUM_INBYTE2 = $RAD_REQUEST{'INTERIUM_INBYTE2'}
-> '0'
(2) perl: &request:INTERIUM_OUTBYTE = $RAD_REQUEST{'INTERIUM_OUTBYTE'}
-> '0'
(2) [perl] = ok
(2) } # accounting = ok
(2) Sent Accounting-Response Id 111 from 62.109.17.189:1813 to
192.168.1.2:33964 length 0
(2) Finished request
(2) Cleaning up request packet ID 111 with timestamp +10
Waking up in 4.9 seconds.
(1) Cleaning up request packet ID 110 with timestamp +10
Ready to process requests
More information about the Freeradius-Users
mailing list