ERROR: Failed to create pair - failed to parse time string
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
On Feb 4, 2020, at 9:17 AM, Никита Борисенков <bna@sunlink.ru> wrote:
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.
Yes.
Is this attribute populated by the service itself?
The attribute should be (a) a 32-bit integer, or (b) English text describing the date.
What can I configure to prevent these errors?
Set the LOCALE for FreeRADIUS to an English one. In short, date parsing is hard. Very hard. The only way we *know* it's correct is to parse what we know: English text. Alan DeKok.
participants (2)
-
Alan DeKok -
Никита Борисенков