Received Accounting-Request Id 46 from 192.168.1.1:44659 to 192.168.1.201:1813 length 222 (4) Acct-Status-Type = Stop (4) Acct-Authentic = RADIUS (4) User-Name = "demo" (4) Called-Station-Id = "C0-56-27-BB-6E-D8:OpenWrt" (4) NAS-Port-Type = Wireless-802.11 (4) Service-Type = Framed-User (4) NAS-Port = 1 (4) Calling-Station-Id = "90-E6-BA-84-5C-7B" (4) Connect-Info = "CONNECT 54Mbps 802.11g" (4) Acct-Session-Id = "30A10F85A2F24680" (4) Attr-186 = 0x000fac04 (4) Attr-187 = 0x000fac04 (4) Attr-188 = 0x000fac01 (4) Event-Timestamp = "Jan 12 2024 00:15:45 CET" (4) Acct-Delay-Time = 45 (4) Acct-Session-Time = 3382 (4) Acct-Input-Packets = 1241 (4) Acct-Output-Packets = 373 (4) Acct-Input-Octets = 136221 (4) Acct-Input-Gigawords = 0 (4) Acct-Output-Octets = 60495 (4) Acct-Output-Gigawords = 0 (4) Acct-Terminate-Cause = User-Request (4) # Executing section preacct from file /etc/freeradius3/sites-enabled/default (4) preacct { (4) [preprocess] = ok (4) policy acct_unique { (4) update request { (4) &Tmp-String-9 := "ai:" (4) } # update request = noop (4) if (("%{hex:&Class}" =~ /^%{hex:&Tmp-String-9}/) && ("%{string:&Class}" =~ /^ai:([0-9a-f]{32})/i)) { (4) EXPAND %{hex:&Class} (4) --> (4) EXPAND ^%{hex:&Tmp-String-9} (4) --> ^61693a (4) if (("%{hex:&Class}" =~ /^%{hex:&Tmp-String-9}/) && ("%{string:&Class}" =~ /^ai:([0-9a-f]{32})/i)) -> FALSE (4) else { (4) update request { (4) EXPAND %{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}} (4) --> d6a8c327880dedeb3e2d553f1951fc1b (4) &Acct-Unique-Session-Id := d6a8c327880dedeb3e2d553f1951fc1b (4) } # update request = noop (4) } # else = noop (4) } # policy acct_unique = noop (4) suffix: Checking for suffix after "@" (4) suffix: No '@' in User-Name = "demo", looking up realm NULL (4) suffix: No such realm "NULL" (4) [suffix] = noop (4) [files] = noop (4) } # preacct = ok (4) # Executing section accounting from file /etc/freeradius3/sites-enabled/default (4) accounting { (4) detail: EXPAND /var/db/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d (4) detail: --> /var/db/radacct/192.168.1.1/detail-20240112 (4) detail: /var/db/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/db/radacct/192.168.1.1/detail-20240112 (4) detail: EXPAND %t (4) detail: --> Fri Jan 12 00:16:30 2024 (4) [detail] = ok (4) [unix] = ok (4) sql: EXPAND %{tolower:type.%{%{Acct-Status-Type}:-%{Request-Processing-Stage}}.query} (4) sql: --> type.stop.query (4) sql: Using query template 'query' rlm_sql (sql): Reserved connection (0) (4) sql: EXPAND %{User-Name} (4) sql: --> demo (4) sql: SQL-User-Name set to 'demo' (4) sql: EXPAND UPDATE radacct SET acctstoptime = FROM_UNIXTIME(%{%{integer:Event-Timestamp}:-%l}), acctsessiontime = %{%{Acct-Session-Time}:-NULL}, acctinputoctets = '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}', acctterminatecause = '%{Acct-Terminate-Cause}', connectinfo_stop = '%{Connect-Info}' WHERE AcctUniqueId = '%{Acct-Unique-Session-Id}' (4) sql: --> UPDATE radacct SET acctstoptime = FROM_UNIXTIME(1705014945), acctsessiontime = 3382, acctinputoctets = '0' << 32 | '136221', acctoutputoctets = '0' << 32 | '60495', acctterminatecause = 'User-Request', connectinfo_stop = 'CONNECT 54Mbps 802.11g' WHERE AcctUniqueId = 'd6a8c327880dedeb3e2d553f1951fc1b' (4) sql: EXPAND /var/log/accounting.sql (4) sql: --> /var/log/accounting.sql (4) sql: Executing query: UPDATE radacct SET acctstoptime = FROM_UNIXTIME(1705014945), acctsessiontime = 3382, acctinputoctets = '0' << 32 | '136221', acctoutputoctets = '0' << 32 | '60495', acctterminatecause = 'User-Request', connectinfo_stop = 'CONNECT 54Mbps 802.11g' WHERE AcctUniqueId = 'd6a8c327880dedeb3e2d553f1951fc1b' rlm_sql_mysql: Rows matched: 0 Changed: 0 Warnings: 0 (4) sql: SQL query returned: success (4) sql: 0 record(s) updated (4) sql: Trying next query... (4) sql: EXPAND INSERT INTO radacct (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctupdatetime, acctstoptime, acctsessiontime, acctauthentic, connectinfo_start, connectinfo_stop, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, acctterminatecause, ) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{%{NAS-Port-ID}:-%{NAS-Port}}', '%{NAS-Port-Type}', FROM_UNIXTIME(%{%{integer:Event-Timestamp}:-%l} - %{%{Acct-Session-Time}:-0}), FROM_UNIXTIME(%{%{integer:Event-Timestamp}:-%l}), FROM_UNIXTIME(%{%{integer:Event-Timestamp}:-%l}), %{%{Acct-Session-Time}:-NULL}, '%{Acct-Authentic}', '', '%{Connect-Info}', '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Acct-Terminate-Cause}', , ) (4) sql: --> INSERT INTO radacct (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctupdatetime, acctstoptime, acctsessiontime, acctauthentic, connectinfo_start, connectinfo_stop, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, acctterminatecause, ) VALUES ('30A10F85A2F24680', 'd6a8c327880dedeb3e2d553f1951fc1b', 'demo', '', '192.168.1.1', '1', 'Wireless-802.11', FROM_UNIXTIME(1705014945 - 3382), FROM_UNIXTIME(1705014945), FROM_UNIXTIME(1705014945), 3382, 'RADIUS', '', 'CONNECT 54Mbps 802.11g', '0' << 32 | '136221', '0' << 32 | '60495', 'C0-56-27-BB-6E-D8:OpenWrt', '90-E6-BA-84-5C-7B', 'User-Request', , ) (4) sql: EXPAND /var/log/accounting.sql (4) sql: --> /var/log/accounting.sql (4) sql: Executing query: INSERT INTO radacct (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctupdatetime, acctstoptime, acctsessiontime, acctauthentic, connectinfo_start, connectinfo_stop, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, acctterminatecause, ) VALUES ('30A10F85A2F24680', 'd6a8c327880dedeb3e2d553f1951fc1b', 'demo', '', '192.168.1.1', '1', 'Wireless-802.11', FROM_UNIXTIME(1705014945 - 3382), FROM_UNIXTIME(1705014945), FROM_UNIXTIME(1705014945), 3382, 'RADIUS', '', 'CONNECT 54Mbps 802.11g', '0' << 32 | '136221', '0' << 32 | '60495', 'C0-56-27-BB-6E-D8:OpenWrt', '90-E6-BA-84-5C-7B', 'User-Request', , ) (4) sql: ERROR: rlm_sql_mysql: ERROR 1064 (You have an error in your SQL syntax; check the manual that corresponds to your MariaDB server version for the right syntax to use near ') VALUES ('30A10F85A2F24680', 'd6a8c327880dedeb3e2d553f1951fc1b', 'demo', '',...' at line 1): 42000 (4) sql: SQL query returned: server error rlm_sql (sql): Released connection (0) (4) [sql] = fail (4) } # accounting = fail (4) Not sending reply to client. (4) Finished request (4) Cleaning up request packet ID 46 with timestamp +51