ERROR: Failed to create pair - failed to parse time string

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

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

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

Re: ERROR: Failed to create pair - failed to parse time string

Alan DeKok-2
On Feb 4, 2020, at 9:17 AM, Никита Борисенков <[hidden email]> 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.


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