Hi All, I'm having a problem that I can't find a solution to. The process I'm using is as follows. User connects to landing page, landing page sends access-request to server with users details. FreeRADIUS then sends a COA to the NAS to change the state and apply attributes. However when FreeRADIUS gets the accounting-start packet, it does nothing with it. Can someone please tell me how I can log this normally in radacct table? In my accounting section i have accounting { sql } sql_xlat finished rlm_sql (sql): Released sql socket id: 4 expand: %{sql:SELECT IFNULL((SELECT IF(ims.vouchers.`data`= 0, 0,IF((ims.vouchers.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets))) < 0,-1, ims.vouchers.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets)))) AS voucherdata FROM radius.radacct,ims.vouchers WHERE radius.radacct.username='%{request:User-Name}' AND radius.radacct.username = ims.vouchers.voucher AND radius.radacct.acctterminatecause<>'Hotspot Restart'),(SELECT ims.vouchers.`data` FROM ims.vouchers WHERE ims.vouchers.voucher='%{request:User-Name}')) as voucherdata} -> 1048576 ++[control] returns noop sql_xlat expand: %{User-Name} -> 10269783 sql_set_user escaped user --> '10269783' expand: SELECT IFNULL((SELECT IF(ims.user_account.`data`= 0, 0,IF((ims.user_account.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets))) < 0,-1, ims.user_account.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets)))) AS voucherdata FROM radius.radacct,ims.user_account WHERE radius.radacct.username='%{request:User-Name}' AND radius.radacct.username = ims.user_account.username AND radius.radacct.acctterminatecause<>'Hotspot Restart'),(SELECT ims.user_account.`data` FROM ims.user_account WHERE ims.user_account.username='%{request:User-Name}')) as voucherdata -> SELECT IFNULL((SELECT IF(ims.user_account.`data`= 0, 0,IF((ims.user_account.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets))) < 0,-1, ims.user_account.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets)))) AS voucherdata FROM radius.radacct,ims.user_account WHERE radius.radacct.username='10269783' AND radius.radacct.username = rlm_sql (sql): Reserving sql socket id: 3 row[0] returned NULL rlm_sql (sql): Released sql socket id: 3 expand: %{sql:SELECT IFNULL((SELECT IF(ims.user_account.`data`= 0, 0,IF((ims.user_account.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets))) < 0,-1, ims.user_account.`data` - (SUM(radius.radacct.acctinputoctets)+SUM(radius.radacct.acctoutputoctets)))) AS voucherdata FROM radius.radacct,ims.user_account WHERE radius.radacct.username='%{request:User-Name}' AND radius.radacct.username = ims.user_account.username AND radius.radacct.acctterminatecause<>'Hotspot Restart'),(SELECT ims.user_account.`data` FROM ims.user_account WHERE ims.user_account.username='%{request:User-Name}')) as voucherdata} -> ++[control] returns noop ++? if (control:IMS-ActiveDirectory == 0) ? Evaluating (control:IMS-ActiveDirectory == 0) -> FALSE ++? if (control:IMS-ActiveDirectory == 0) -> FALSE ++? if (control:IMS-Timeout < 0) ? Evaluating (control:IMS-Timeout < 0) -> FALSE ++? if (control:IMS-Timeout < 0) -> FALSE ++? elsif (control:IMS-Timeout > 0) ? Evaluating (control:IMS-Timeout > 0) -> FALSE ++? elsif (control:IMS-Timeout > 0) -> FALSE ++? if (control:IMS-Data < 0) ? Evaluating (control:IMS-Data < 0) -> FALSE ++? if (control:IMS-Data < 0) -> FALSE ++? if (control:IMS-Data > 0) ? Evaluating (control:IMS-Data > 0) -> TRUE ++? if (control:IMS-Data > 0) -> TRUE ++- entering if (control:IMS-Data > 0) {...} expand: %{control:IMS-Data} -> 1048576 +++[reply] returns noop ++- if (control:IMS-Data > 0) returns noop ++? if (control:IMS-UserData < 0) ? Evaluating (control:IMS-UserData < 0) -> FALSE ++? if (control:IMS-UserData < 0) -> FALSE ++? if (control:IMS-UserData > 0) ? Evaluating (control:IMS-UserData > 0) -> FALSE ++? if (control:IMS-UserData > 0) -> FALSE ++[reply] returns noop } # server ims Sending Access-Accept of id 79 to 127.0.0.1 port 51340 Trapeze-Qos-Profile = "MB100" Mikrotik-Total-Limit = 1048576 Acct-Interim-Interval = 120 # Executing section pre-proxy from file /etc/freeradius/sites-enabled/ims +- entering group pre-proxy {...} [detail] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/freeradius/radacct/127.0.0.1/detail-20130501 [detail] /var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/freeradius/radacct/127.0.0.1/detail-20130501 [detail] expand: %t -> Wed May 1 09:58:20 2013 ++[detail] returns ok Sending CoA-Request of id 91 to 172.16.255.35 port 3799 Calling-Station-Id = "04:54:53:85:ca:82" NAS-IP-Address = 172.16.255.35 Trapeze-COA-Replace-Username = "10269783" Filter-Id = "MB100" Finished request 3. Going to the next request Waking up in 2.4 seconds. rad_recv: CoA-ACK packet from host 172.16.255.35 port 3799, id=91, length=26 Event-Timestamp = "May 1 2013 09:58:34 SAST" # Executing section post-proxy from file /etc/freeradius/sites-enabled/ims +- entering group post-proxy {...} [detail] expand: /var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/freeradius/radacct/127.0.0.1/detail-20130501 [detail] /var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/freeradius/radacct/127.0.0.1/detail-20130501 [detail] expand: %t -> Wed May 1 09:58:20 2013 ++[detail] returns ok server ims { } # server ims Finished request 3. Going to the next request Waking up in 4.9 seconds. rad_recv: Accounting-Request packet from host 172.16.255.35 port 20000, id=200, length=244 Acct-Status-Type = Start Acct-Multi-Session-Id = "SESS-464-72c952-395089-4201e" Acct-Session-Id = "SESS-464-72c952-395089-4201e" User-Name = "10269783" Event-Timestamp = "May 1 2013 09:58:34 SAST" Trapeze-VLAN-Name = "DataA" Calling-Station-Id = "04-54-53-85-CA-82" NAS-Port-Id = "AP13/2" Called-Station-Id = "AC-4B-C8-02-23-41:BTC HOTSPOT - FAIRGROUND" Trapeze-Attr-19 = 0x69706164 Trapeze-Attr-21 = 0x696f73 NAS-Port = 464 Framed-IP-Address = 172.16.100.18 NAS-Port-Type = Wireless-802.11 NAS-IP-Address = 172.16.255.35 NAS-Identifier = "XON" Acct-Delay-Time = 0 WARNING: Empty preacct section. Using default return values. WARNING: Empty accounting section. Using default return values. Finished request 4. Cleaning up request 4 ID 200 with timestamp +47 Going to the next request Waking up in 4.9 seconds. Cleaning up request 3 ID 79 with timestamp +47 rad_recv: Accounting-Request packet from host 172.16.255.35 port 20000, id=201, length=244 Acct-Status-Type = Start Acct-Multi-Session-Id = "SESS-464-72c952-395089-4201e" Acct-Session-Id = "SESS-464-72c952-395089-4201e" User-Name = "10269783" Event-Timestamp = "May 1 2013 09:58:34 SAST" Trapeze-VLAN-Name = "DataA" Calling-Station-Id = "04-54-53-85-CA-82" NAS-Port-Id = "AP13/2" Called-Station-Id = "AC-4B-C8-02-23-41:BTC HOTSPOT - FAIRGROUND" Trapeze-Attr-19 = 0x69706164 Trapeze-Attr-21 = 0x696f73 NAS-Port = 464 Framed-IP-Address = 172.16.100.18 NAS-Port-Type = Wireless-802.11 NAS-IP-Address = 172.16.255.35 NAS-Identifier = "XON" Acct-Delay-Time = 5 WARNING: Empty preacct section. Using default return values. WARNING: Empty accounting section. Using default return values. Finished request 5. Cleaning up request 5 ID 201 with timestamp +52 Going to the next request Ready to process requests. rad_recv: Accounting-Request packet from host 172.16.255.35 port 20000, id=202, length=244 Acct-Status-Type = Start Acct-Multi-Session-Id = "SESS-464-72c952-395089-4201e" Acct-Session-Id = "SESS-464-72c952-395089-4201e" User-Name = "10269783" Event-Timestamp = "May 1 2013 09:58:34 SAST" Trapeze-VLAN-Name = "DataA" Calling-Station-Id = "04-54-53-85-CA-82" NAS-Port-Id = "AP13/2" Called-Station-Id = "AC-4B-C8-02-23-41:BTC HOTSPOT - FAIRGROUND" Trapeze-Attr-19 = 0x69706164 Trapeze-Attr-21 = 0x696f73 NAS-Port = 464 Framed-IP-Address = 172.16.100.18 NAS-Port-Type = Wireless-802.11 NAS-IP-Address = 172.16.255.35 NAS-Identifier = "XON" Acct-Delay-Time = 10 WARNING: Empty preacct section. Using default return values. WARNING: Empty accounting section. Using default return values. Finished request 6. Cleaning up request 6 ID 202 with timestamp +57 Going to the next request Ready to process requests. rad_recv: Accounting-Request packet from host 172.16.255.35 port 20000, id=203, length=274 Acct-Status-Type = Stop Acct-Multi-Session-Id = "SESS-464-72c952-395089-4201e" Acct-Session-Id = "SESS-464-72c952-395089-4201e" User-Name = "10269783" Event-Timestamp = "May 1 2013 10:03:16 SAST" Trapeze-VLAN-Name = "DataA" Calling-Station-Id = "04-54-53-85-CA-82" NAS-Port-Id = "AP13/2" Called-Station-Id = "AC-4B-C8-02-23-41:BTC HOTSPOT - FAIRGROUND" Trapeze-Attr-19 = 0x69706164 Trapeze-Attr-21 = 0x696f73 NAS-Port = 464 Framed-IP-Address = 172.16.100.18 NAS-Identifier = "XON" Acct-Session-Time = 282 Acct-Output-Octets = 58993 Acct-Input-Octets = 35737 Acct-Output-Packets = 177 Acct-Input-Packets = 393 NAS-Port-Type = Wireless-802.11 NAS-IP-Address = 172.16.255.35 Acct-Delay-Time = 0 WARNING: Empty preacct section. Using default return values. WARNING: Empty accounting section. Using default return values. Finished request 7. Cleaning up request 7 ID 203 with timestamp +329 Going to the next request Ready to process requests. rad_recv: Accounting-Request packet from host 172.16.255.35 port 20000, id=204, length=274 Acct-Status-Type = Stop Acct-Multi-Session-Id = "SESS-464-72c952-395089-4201e" Acct-Session-Id = "SESS-464-72c952-395089-4201e" User-Name = "10269783" Event-Timestamp = "May 1 2013 10:03:16 SAST" Trapeze-VLAN-Name = "DataA" Calling-Station-Id = "04-54-53-85-CA-82" NAS-Port-Id = "AP13/2" Called-Station-Id = "AC-4B-C8-02-23-41:BTC HOTSPOT - FAIRGROUND" Trapeze-Attr-19 = 0x69706164 Trapeze-Attr-21 = 0x696f73 NAS-Port = 464 Framed-IP-Address = 172.16.100.18 NAS-Identifier = "XON" Acct-Session-Time = 282 Acct-Output-Octets = 58993 Acct-Input-Octets = 35737 Acct-Output-Packets = 177 Acct-Input-Packets = 393 NAS-Port-Type = Wireless-802.11 NAS-IP-Address = 172.16.255.35 Acct-Delay-Time = 5 WARNING: Empty preacct section. Using default return values. WARNING: Empty accounting section. Using default return values. Finished request 8. Cleaning up request 8 ID 204 with timestamp +334 Going to the next request Ready to process requests. rad_recv: Accounting-Request packet from host 172.16.255.35 port 20000, id=205, length=274 Acct-Status-Type = Stop Acct-Multi-Session-Id = "SESS-464-72c952-395089-4201e" Acct-Session-Id = "SESS-464-72c952-395089-4201e" User-Name = "10269783" Event-Timestamp = "May 1 2013 10:03:16 SAST" Trapeze-VLAN-Name = "DataA" Calling-Station-Id = "04-54-53-85-CA-82" NAS-Port-Id = "AP13/2" Called-Station-Id = "AC-4B-C8-02-23-41:BTC HOTSPOT - FAIRGROUND" Trapeze-Attr-19 = 0x69706164 Trapeze-Attr-21 = 0x696f73 NAS-Port = 464 Framed-IP-Address = 172.16.100.18 NAS-Identifier = "XON" Acct-Session-Time = 282 Acct-Output-Octets = 58993 Acct-Input-Octets = 35737 Acct-Output-Packets = 177 Acct-Input-Packets = 393 NAS-Port-Type = Wireless-802.11 NAS-IP-Address = 172.16.255.35 Acct-Delay-Time = 10 WARNING: Empty preacct section. Using default return values. WARNING: Empty accounting section. Using default return values. Finished request 9. Cleaning up request 9 ID 205 with timestamp +339 Going to the next request Ready to process requests.