Ready to process requests (0) Received Access-Request Id 45 from 127.0.0.1:58655 to 127.0.0.1:1812 length 79 (0) User-Name = "rba_user1" (0) User-Password = "rba_user1" (0) NAS-IP-Address = 172.16.49.8 (0) NAS-Port = 0 (0) Message-Authenticator = 0x4a58fd7428ab01eb65f3d6c080373563 (0) # Executing section authorize from file /etc/freeradius/3.0/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 !~ /@(.+)\.(.+)$/)) { (0) if ((&User-Name =~ /@/) && (&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) = notfound (0) } # policy filter_username = notfound rlm_rest (rest): Closing connection (0): Hit idle_timeout, was idle for 149 seconds rlm_rest (rest): Closing connection (1): Hit idle_timeout, was idle for 149 seconds rlm_rest (rest): Closing connection (2): Hit idle_timeout, was idle for 148 seconds rlm_rest (rest): You probably need to lower "min" rlm_rest (rest): Closing connection (3): Hit idle_timeout, was idle for 148 seconds rlm_rest (rest): You probably need to lower "min" rlm_rest (rest): Closing connection (4): Hit idle_timeout, was idle for 147 seconds rlm_rest (rest): You probably need to lower "min" rlm_rest (rest): 0 of 0 connections in use. You may need to increase "spare" rlm_rest (rest): Opening additional connection (5), 1 of 32 pending slots used rlm_rest (rest): Connecting to "https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io/api/v1/auth/login" rlm_rest (rest): Reserved connection (5) (0) rest: Expanding URI components (0) rest: EXPAND https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io (0) rest: --> https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io (0) rest: EXPAND /api/v1/auth/login (0) rest: --> /api/v1/auth/login (0) rest: Sending HTTP POST to "https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io/api/v1/auth/login" (0) rest: EXPAND {"username": "%{urlquote:%{User-Name}}", "password": "%{urlquote:%{User-Password}}"} (0) rest: --> {"username": "rba_user1", "password": "rba_user1"} (0) rest: Processing response header (0) rest: Status : 200 (OK) (0) rest: Type : json (application/json) (0) rest: Parsing attribute "username" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "username", skipping... (0) rest: Parsing attribute "firstname" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "firstname", skipping... (0) rest: Parsing attribute "lastname" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "lastname", skipping... (0) rest: Parsing attribute "locationId" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "locationId", skipping... (0) rest: Parsing attribute "id" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "id", skipping... (0) rest: Parsing attribute "email" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "email", skipping... (0) rest: Parsing attribute "access_token" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "access_token", skipping... rlm_rest (rest): Released connection (5) Need 2 more connections to reach min connections (3) rlm_rest (rest): Opening additional connection (6), 1 of 31 pending slots used rlm_rest (rest): Connecting to "https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io/api/v1/auth/login" (0) [rest] = ok (0) [preprocess] = ok (0) eap: No EAP-Message, not doing EAP (0) [eap] = noop (0) files: users: Matched entry DEFAULT at line 176 (0) [files] = ok (0) [expiration] = noop (0) [logintime] = noop (0) pap: WARNING: No "known good" password found for the user. Not setting Auth-Type (0) pap: WARNING: Authentication will fail unless a "known good" password is available (0) [pap] = noop (0) } # authorize = ok (0) Found Auth-Type = rest (0) # Executing group from file /etc/freeradius/3.0/sites-enabled/default (0) authenticate { rlm_rest (rest): Reserved connection (5) (0) rest: Expanding URI components (0) rest: EXPAND https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io (0) rest: --> https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io (0) rest: EXPAND /api/v1/auth/login (0) rest: --> /api/v1/auth/login (0) rest: Sending HTTP POST to "https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io/api/v1/auth/login" (0) rest: EXPAND {"username": "%{urlquote:%{User-Name}}", "password": "%{urlquote:%{User-Password}}"} (0) rest: --> {"username": "rba_user1", "password": "rba_user1"} (0) rest: Processing response header (0) rest: Status : 200 (OK) (0) rest: Type : json (application/json) (0) rest: Parsing attribute "username" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "username", skipping... (0) rest: Parsing attribute "firstname" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "firstname", skipping... (0) rest: Parsing attribute "lastname" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "lastname", skipping... (0) rest: Parsing attribute "locationId" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "locationId", skipping... (0) rest: Parsing attribute "id" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "id", skipping... (0) rest: Parsing attribute "email" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "email", skipping... (0) rest: Parsing attribute "access_token" (0) rest: WARNING: Failed parsing attribute: Invalid vendor name in attribute name "access_token", skipping... rlm_rest (rest): Released connection (5) Need 1 more connections to reach min connections (3) rlm_rest (rest): Opening additional connection (7), 1 of 30 pending slots used rlm_rest (rest): Connecting to "https://6c14825b-a5cc-48a1-a0a7-723b78f0a90e.mock.pstmn.io/api/v1/auth/login" (0) [rest] = ok (0) } # authenticate = ok (0) # Executing section post-auth from file /etc/freeradius/3.0/sites-enabled/default (0) post-auth { (0) update { (0) No attributes updated (0) } # update = noop (0) sql: EXPAND .query (0) sql: --> .query (0) sql: Using query template 'query' rlm_sql (sql): Closing connection (1): Hit idle_timeout, was idle for 149 seconds rlm_sql_mysql: Socket destructor called, closing socket rlm_sql (sql): Closing connection (2): Hit idle_timeout, was idle for 149 seconds rlm_sql_mysql: Socket destructor called, closing socket rlm_sql (sql): Closing connection (3): Hit idle_timeout, was idle for 149 seconds rlm_sql_mysql: Socket destructor called, closing socket rlm_sql (sql): Closing connection (4): Hit idle_timeout, was idle for 149 seconds rlm_sql (sql): You probably need to lower "min" rlm_sql_mysql: Socket destructor called, closing socket rlm_sql (sql): Closing connection (0): Hit idle_timeout, was idle for 149 seconds rlm_sql (sql): You probably need to lower "min" rlm_sql_mysql: Socket destructor called, closing socket rlm_sql (sql): Closing connection (5): Hit idle_timeout, was idle for 149 seconds rlm_sql (sql): You probably need to lower "min" rlm_sql_mysql: Socket destructor called, closing socket rlm_sql (sql): 0 of 0 connections in use. You may need to increase "spare" rlm_sql (sql): Opening additional connection (6), 1 of 32 pending slots used rlm_sql_mysql: Starting connect to MySQL server rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX socket, server version 5.7.36-0ubuntu0.18.04.1, protocol version 10 rlm_sql (sql): Reserved connection (6) (0) sql: EXPAND %{User-Name} (0) sql: --> rba_user1 (0) sql: SQL-User-Name set to 'rba_user1' (0) sql: EXPAND INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ( '%{SQL-User-Name}', '%{%{User-Password}:-%{Chap-Password}}', '%{reply:Packet-Type}', '%S') (0) sql: --> INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ( 'rba_user1', 'rba_user1', 'Access-Accept', '2021-12-02 08:27:19') (0) sql: Executing query: INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ( 'rba_user1', 'rba_user1', 'Access-Accept', '2021-12-02 08:27:19') (0) sql: SQL query returned: success (0) sql: 1 record(s) updated rlm_sql (sql): Released connection (6) Need 2 more connections to reach min connections (3) rlm_sql (sql): Opening additional connection (7), 1 of 31 pending slots used rlm_sql_mysql: Starting connect to MySQL server rlm_sql_mysql: Connected to database 'radius' on Localhost via UNIX socket, server version 5.7.36-0ubuntu0.18.04.1, protocol version 10 (0) [sql] = ok (0) [exec] = noop (0) policy remove_reply_message_if_eap { (0) if (&reply:EAP-Message && &reply:Reply-Message) { (0) if (&reply:EAP-Message && &reply:Reply-Message) -> FALSE (0) else { (0) [noop] = noop (0) } # else = noop (0) } # policy remove_reply_message_if_eap = noop (0) } # post-auth = ok (0) Sent Access-Accept Id 45 from 127.0.0.1:1812 to 127.0.0.1:58655 length 0 (0) Finished request Waking up in 4.9 seconds. (0) Cleaning up request packet ID 45 with timestamp +147 Ready to process requests