Mon Jul 5 17:54:43 2021 : Info: Ready to process requests Mon Jul 5 17:56:17 2021 : Debug: (4) Received Accounting-Request Id 45 from 103.136. 204.45:43532 to 172.26.11.27:1813 length 202 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Status-Type = Stop Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Terminate-Cause = NAS-Request Mon Jul 5 17:56:17 2021 : Debug: (4) NAS-Port-Type = Wireless-802.11 Mon Jul 5 17:56:17 2021 : Debug: (4) Calling-Station-Id = "00:0C:29:D6:B8:A6" Mon Jul 5 17:56:17 2021 : Debug: (4) Called-Station-Id = "hotspot1" Mon Jul 5 17:56:17 2021 : Debug: (4) NAS-Port-Id = "ether3-LAN" Mon Jul 5 17:56:17 2021 : Debug: (4) User-Name = "06767" Mon Jul 5 17:56:17 2021 : Debug: (4) NAS-Port = 2149580826 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Session-Id = "8020001a" Mon Jul 5 17:56:17 2021 : Debug: (4) Framed-IP-Address = 192.168.251.117 Mon Jul 5 17:56:17 2021 : Debug: (4) Mikrotik-Host-IP = 192.168.251.117 Mon Jul 5 17:56:17 2021 : Debug: (4) Event-Timestamp = "Jul 5 2021 17:56:17 +04" Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Input-Octets = 21740756 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Output-Octets = 2125744426 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Input-Gigawords = 0 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Output-Gigawords = 0 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Input-Packets = 498913 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Output-Packets = 487557 Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Session-Time = 694 Mon Jul 5 17:56:17 2021 : Debug: (4) NAS-Identifier = "Nayeem-R1-CoreRouter" Mon Jul 5 17:56:17 2021 : Debug: (4) Acct-Delay-Time = 0 Mon Jul 5 17:56:17 2021 : Debug: (4) NAS-IP-Address = 192.168.31.77 Mon Jul 5 17:56:17 2021 : Debug: (4) # Executing section preacct from file /etc/free radius/3.0/sites-enabled/default Mon Jul 5 17:56:17 2021 : Debug: (4) preacct { Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[preacct]: calling preprocess (rlm _preprocess) Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[preacct]: returned from preproces s (rlm_preprocess) Mon Jul 5 17:56:17 2021 : Debug: (4) [preprocess] = ok Mon Jul 5 17:56:17 2021 : Debug: (4) policy acct_unique { Mon Jul 5 17:56:17 2021 : Debug: (4) update request { Mon Jul 5 17:56:17 2021 : Debug: (4) &Tmp-String-9 := "ai:" Mon Jul 5 17:56:17 2021 : Debug: (4) } # update request = noop Mon Jul 5 17:56:17 2021 : Debug: (4) if (("%{hex:&Class}" =~ /^%{hex:&Tmp-Stri ng-9}/) && ("%{string:&Class}" =~ /^ai:([0-9a-f]{32})/i)) { Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND TMPL XLAT STRUCT Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{hex:&Class} Mon Jul 5 17:56:17 2021 : Debug: (4) --> Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND TMPL XLAT STRUCT Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND ^%{hex:&Tmp-String-9} Mon Jul 5 17:56:17 2021 : Debug: (4) --> ^61693a Mon Jul 5 17:56:17 2021 : Debug: No matches Mon Jul 5 17:56:17 2021 : Debug: (4) if (("%{hex:&Class}" =~ /^%{hex:&Tmp-Stri ng-9}/) && ("%{string:&Class}" =~ /^ai:([0-9a-f]{32})/i)) -> FALSE Mon Jul 5 17:56:17 2021 : Debug: (4) else { Mon Jul 5 17:56:17 2021 : Debug: (4) update request { Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{md5:%{User-Name},%{Acct-Sess ion-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{ NAS-Port}} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 3f74fa78ccd63a57e895ef24572721 40 Mon Jul 5 17:56:17 2021 : Debug: (4) &Acct-Unique-Session-Id := 3f74fa78cc d63a57e895ef2457272140 Mon Jul 5 17:56:17 2021 : Debug: (4) } # update request = noop Mon Jul 5 17:56:17 2021 : Debug: (4) } # else = noop Mon Jul 5 17:56:17 2021 : Debug: (4) } # policy acct_unique = noop Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[preacct]: calling suffix (rlm_rea lm) Mon Jul 5 17:56:17 2021 : Debug: (4) suffix: Checking for suffix after "@" Mon Jul 5 17:56:17 2021 : Debug: (4) suffix: No '@' in User-Name = "06767", looking up realm NULL Mon Jul 5 17:56:17 2021 : Debug: (4) suffix: No such realm "NULL" Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[preacct]: returned from suffix (r lm_realm) Mon Jul 5 17:56:17 2021 : Debug: (4) [suffix] = noop Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[preacct]: calling files (rlm_file s) Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[preacct]: returned from files (rl m_files) Mon Jul 5 17:56:17 2021 : Debug: (4) [files] = noop Mon Jul 5 17:56:17 2021 : Debug: (4) } # preacct = ok Mon Jul 5 17:56:17 2021 : Debug: (4) # Executing section accounting from file /etc/f reeradius/3.0/sites-enabled/default Mon Jul 5 17:56:17 2021 : Debug: (4) accounting { Mon Jul 5 17:56:17 2021 : Debug: (4) update control { Mon Jul 5 17:56:17 2021 : Debug: %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: attribute --> User-Name Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 06767 Mon Jul 5 17:56:17 2021 : Debug: (4) SQL-User-Name set to '06767' Mon Jul 5 17:56:17 2021 : Info: rlm_sql (sql): Closing connection (9): Hit idle_time out, was idle for 94 seconds Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): You probably need to lower "min" Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: Socket destructor called, closing so cket Mon Jul 5 17:56:17 2021 : Info: rlm_sql (sql): Closing connection (10): Hit idle_tim eout, was idle for 94 seconds Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): You probably need to lower "min" Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: Socket destructor called, closing so cket Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): 0 of 0 connections in use. You may need to increase "spare" Mon Jul 5 17:56:17 2021 : Info: rlm_sql (sql): Opening additional connection (11), 1 of 32 pending slots used Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: Starting connect to MySQL server Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: Connected to database 'radius' on Lo calhost via UNIX socket, server version 5.7.34-0ubuntu0.18.04.1, protocol version 10 Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Reserved connection (11) Mon Jul 5 17:56:17 2021 : Debug: (4) Executing select query: SELECT (SUM(accti nputOctets)+SUM(acctoutputoctets)) AS Total FROM radacct WHERE radacct.username='0676 7' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Released connection (11) Mon Jul 5 17:56:17 2021 : Info: Need 2 more connections to reach min connections (3) Mon Jul 5 17:56:17 2021 : Info: rlm_sql (sql): Opening additional connection (12), 1 of 31 pending slots used Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: Starting connect to MySQL server Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: Connected to database 'radius' on Lo calhost via UNIX socket, server version 5.7.34-0ubuntu0.18.04.1, protocol version 10 Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{sql:SELECT (SUM(acctinputOctets) +SUM(acctoutputoctets)) AS Total FROM radacct WHERE radacct.username='%{User-Name}'} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 1802278260 Mon Jul 5 17:56:17 2021 : Debug: (4) Tmp-Integer-0 := 1802278260 Mon Jul 5 17:56:17 2021 : Debug: %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: attribute --> User-Name Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 06767 Mon Jul 5 17:56:17 2021 : Debug: (4) SQL-User-Name set to '06767' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Reserved connection (11) Mon Jul 5 17:56:17 2021 : Debug: (4) Executing select query: SELECT value FRO M radcheck WHERE attribute='Max-All-MB’ AND username='06767' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: No additional diagnostic info on ser ver Mon Jul 5 17:56:17 2021 : ERROR: (4) rlm_sql_mysql: ERROR 1064 (You have an er ror in your SQL syntax; check the manual that corresponds to your MySQL server versio n for the right syntax to use near '06767'' at line 1): 42000 Mon Jul 5 17:56:17 2021 : ERROR: (4) SQL query failed: server error Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Released connection (11) Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{sql: SELECT value FROM radcheck WHERE attribute='Max-All-MB’ AND username='%{User-Name}'} Mon Jul 5 17:56:17 2021 : Debug: (4) --> Mon Jul 5 17:56:17 2021 : Debug: (4) Tmp-String-1 := Mon Jul 5 17:56:17 2021 : Debug: %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: attribute --> User-Name Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 06767 Mon Jul 5 17:56:17 2021 : Debug: (4) SQL-User-Name set to '06767' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Reserved connection (12) Mon Jul 5 17:56:17 2021 : Debug: (4) Executing select query: SELECT value FRO M radgroupcheck WHERE attribute='Max-All-MB' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Released connection (12) Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{sql: SELECT value FROM radgroupc heck WHERE attribute='Max-All-MB'} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 10737418240 Mon Jul 5 17:56:17 2021 : Debug: (4) Tmp-String-1 := 10737418240 Mon Jul 5 17:56:17 2021 : Debug: (4) Overwriting value "" with "10737418240" Mon Jul 5 17:56:17 2021 : Debug: %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: attribute --> User-Name Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 06767 Mon Jul 5 17:56:17 2021 : Debug: (4) SQL-User-Name set to '06767' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Reserved connection (11) Mon Jul 5 17:56:17 2021 : Debug: (4) Executing select query: select calledstat ionid from radacct where acctsessionid='8020001a' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Released connection (11) Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{sql:select calledstationid from radacct where acctsessionid='%{Acct-Session-Id}'} Mon Jul 5 17:56:17 2021 : Debug: (4) --> hotspot1 Mon Jul 5 17:56:17 2021 : Debug: (4) Tmp-String-3 := hotspot1 Mon Jul 5 17:56:17 2021 : Debug: (4) } # update control = noop Mon Jul 5 17:56:17 2021 : Debug: (4) if ("%{control:Tmp-Integer-0}" > "%{control :Tmp-String-1}"){ Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND TMPL XLAT STRUCT Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{control:Tmp-Integer-0} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 1802278260 Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND TMPL XLAT STRUCT Mon Jul 5 17:56:17 2021 : Debug: (4) EXPAND %{control:Tmp-String-1} Mon Jul 5 17:56:17 2021 : Debug: (4) --> 10737418240 Mon Jul 5 17:56:17 2021 : Debug: (4) if ("%{control:Tmp-Integer-0}" > "%{control :Tmp-String-1}") -> FALSE Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: calling detail (rlm_ detail) Mon Jul 5 17:56:17 2021 : Debug: /var/log/freeradius/radacct/%{%{Packet-Src-IP-Addre ss}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: literal --> /var/log/freeradius/radacct/ Mon Jul 5 17:56:17 2021 : Debug: XLAT-IF { Mon Jul 5 17:56:17 2021 : Debug: attribute --> Packet-Src-IP-Address Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: XLAT-ELSE { Mon Jul 5 17:56:17 2021 : Debug: attribute --> Packet-Src-IPv6-Address Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: literal --> /detail- Mon Jul 5 17:56:17 2021 : Debug: percent --> Y Mon Jul 5 17:56:17 2021 : Debug: percent --> m Mon Jul 5 17:56:17 2021 : Debug: percent --> d Mon Jul 5 17:56:17 2021 : Debug: (4) detail: EXPAND /var/log/freeradius/radacct/%{%{ Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d Mon Jul 5 17:56:17 2021 : Debug: (4) detail: --> /var/log/freeradius/radacct/103. 136.204.45/detail-20210705 Mon Jul 5 17:56:17 2021 : Debug: (4) detail: /var/log/freeradius/radacct/%{%{Packet- Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/freera dius/radacct/103.136.204.45/detail-20210705 Mon Jul 5 17:56:17 2021 : Debug: %t Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: percent --> t Mon Jul 5 17:56:17 2021 : Debug: (4) detail: EXPAND %t Mon Jul 5 17:56:17 2021 : Debug: (4) detail: --> Mon Jul 5 17:56:17 2021 Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: returned from detail (rlm_detail) Mon Jul 5 17:56:17 2021 : Debug: (4) [detail] = ok Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: calling unix (rlm_un ix) Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: returned from unix ( rlm_unix) Mon Jul 5 17:56:17 2021 : Debug: (4) [unix] = ok Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: calling sql (rlm_sql ) Mon Jul 5 17:56:17 2021 : Debug: %{tolower:type.%{Acct-Status-Type}.query} Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: xlat --> tolower Mon Jul 5 17:56:17 2021 : Debug: { Mon Jul 5 17:56:17 2021 : Debug: literal --> type. Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Status-Type Mon Jul 5 17:56:17 2021 : Debug: literal --> .query Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: (4) sql: EXPAND %{tolower:type.%{Acct-Status-Type}. query} Mon Jul 5 17:56:17 2021 : Debug: (4) sql: --> type.stop.query Mon Jul 5 17:56:17 2021 : Debug: (4) sql: Using query template 'query' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Reserved connection (12) Mon Jul 5 17:56:17 2021 : Debug: %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: attribute --> User-Name Mon Jul 5 17:56:17 2021 : Debug: (4) sql: EXPAND %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: (4) sql: --> 06767 Mon Jul 5 17:56:17 2021 : Debug: (4) sql: SQL-User-Name set to '06767' Mon Jul 5 17:56:17 2021 : Debug: UPDATE radacct SET acctstoptime = FROM_UNIXTI ME(%{integer:Event-Timestamp}), acctsessiontime = %{%{Acct-Session-Time}:-NULL}, acct inputoctets = '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0} ', acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octet s}:-0}', acctterminatecause = '%{Acct-Terminate-Cause}', connectinfo_stop = '%{Connec t-Info}' WHERE AcctUniqueId = '%{Acct-Unique-Session-Id}' Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: literal --> UPDATE radacct SET acctstoptime = FRO M_UNIXTIME( Mon Jul 5 17:56:17 2021 : Debug: xlat --> integer Mon Jul 5 17:56:17 2021 : Debug: { Mon Jul 5 17:56:17 2021 : Debug: literal --> Event-Timestamp Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: literal --> ), acctsessiontime = Mon Jul 5 17:56:17 2021 : Debug: XLAT-IF { Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Session-Time Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: XLAT-ELSE { Mon Jul 5 17:56:17 2021 : Debug: literal --> NULL Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: literal --> , acctinputoctets = ' Mon Jul 5 17:56:17 2021 : Debug: XLAT-IF { Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Input-Gigawords Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: XLAT-ELSE { Mon Jul 5 17:56:17 2021 : Debug: literal --> 0 Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: literal --> ' << 32 | ' Mon Jul 5 17:56:17 2021 : Debug: XLAT-IF { Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Input-Octets Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: XLAT-ELSE { Mon Jul 5 17:56:17 2021 : Debug: literal --> 0 Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: literal --> ', acctoutputoctets = ' Mon Jul 5 17:56:17 2021 : Debug: XLAT-IF { Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Output-Gigawords Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: XLAT-ELSE { Mon Jul 5 17:56:17 2021 : Debug: literal --> 0 Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: literal --> ' << 32 | ' Mon Jul 5 17:56:17 2021 : Debug: XLAT-IF { Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Output-Octets Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: XLAT-ELSE { Mon Jul 5 17:56:17 2021 : Debug: literal --> 0 Mon Jul 5 17:56:17 2021 : Debug: } Mon Jul 5 17:56:17 2021 : Debug: literal --> ', acctterminatecause = ' Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Terminate-Cause Mon Jul 5 17:56:17 2021 : Debug: literal --> ', connectinfo_stop = ' Mon Jul 5 17:56:17 2021 : Debug: attribute --> Connect-Info Mon Jul 5 17:56:17 2021 : Debug: literal --> ' WHERE AcctUniqueId = ' Mon Jul 5 17:56:17 2021 : Debug: attribute --> Acct-Unique-Session-Id Mon Jul 5 17:56:17 2021 : Debug: literal --> ' Mon Jul 5 17:56:17 2021 : Debug: (4) sql: EXPAND UPDATE radacct SET acctstoptime = FROM_UNIXTIME(%{integer:Event-Timestamp}), acctsessiontime = %{%{Acct-Session-Ti me}:-NULL}, acctinputoctets = '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-I nput-Octets}:-0}', acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{A cct-Output-Octets}:-0}', acctterminatecause = '%{Acct-Terminate-Cause}', connectinfo_ stop = '%{Connect-Info}' WHERE AcctUniqueId = '%{Acct-Unique-Session-Id}' Mon Jul 5 17:56:17 2021 : Debug: (4) sql: --> UPDATE radacct SET acctstoptime = FROM_UNIXTIME(1625493377), acctsessiontime = 694, acctinputoctets = '0' << 32 | '21740756', acctoutputoctets = '0' << 32 | '2125744426', acctterminatecause = 'NAS-R equest', connectinfo_stop = '' WHERE AcctUniqueId = '3f74fa78ccd63a57e895ef2457272140 ' Mon Jul 5 17:56:17 2021 : Debug: (4) sql: Executing query: UPDATE radacct SET acctst optime = FROM_UNIXTIME(1625493377), acctsessiontime = 694, acctinputoctets = '0' << 32 | '21740756', acctoutputoctets = '0' << 32 | '2125744426', acctterminatecause = 'NAS-Request', connectinfo_stop = '' WHERE AcctUniqueId = '3f74fa78ccd63a57e895ef24 57272140' Mon Jul 5 17:56:17 2021 : Debug: rlm_sql_mysql: Rows matched: 1 Changed: 1 Warning s: 0 Mon Jul 5 17:56:17 2021 : Debug: (4) sql: SQL query returned: success Mon Jul 5 17:56:17 2021 : Debug: (4) sql: 1 record(s) updated Mon Jul 5 17:56:17 2021 : Debug: rlm_sql (sql): Released connection (12) Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: returned from sql (r lm_sql) Mon Jul 5 17:56:17 2021 : Debug: (4) [sql] = ok Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: calling exec (rlm_ex ec) Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: returned from exec ( rlm_exec) Mon Jul 5 17:56:17 2021 : Debug: (4) [exec] = noop Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: calling attr_filter. accounting_response (rlm_attr_filter) Mon Jul 5 17:56:17 2021 : Debug: %{User-Name} Mon Jul 5 17:56:17 2021 : Debug: Parsed xlat tree: Mon Jul 5 17:56:17 2021 : Debug: attribute --> User-Name Mon Jul 5 17:56:17 2021 : Debug: (4) attr_filter.accounting_response: EXPAND %{User- Name} Mon Jul 5 17:56:17 2021 : Debug: (4) attr_filter.accounting_response: --> 06767 Mon Jul 5 17:56:17 2021 : Debug: (4) attr_filter.accounting_response: Matched entry DEFAULT at line 12 Mon Jul 5 17:56:17 2021 : Debug: (4) modsingle[accounting]: returned from attr_f ilter.accounting_response (rlm_attr_filter) Mon Jul 5 17:56:17 2021 : Debug: (4) [attr_filter.accounting_response] = updated Mon Jul 5 17:56:17 2021 : Debug: (4) } # accounting = updated Mon Jul 5 17:56:17 2021 : Debug: (4) Sent Accounting-Response Id 45 from 172.26.11.2 7:1813 to 103.136.204.45:43532 length 0 Mon Jul 5 17:56:17 2021 : Debug: (4) Finished request Mon Jul 5 17:56:17 2021 : Debug: (4) Cleaning up request packet ID 45 with timestamp +752 Mon Jul 5 17:56:17 2021 : Info: Ready to process requests