acct-request stop packet recv by radiusd but not sending acct-response

[Duane Cox]

I've been getting bombarded with old acct info, and looking into it, I
believe the reason the NAS keeps sending the same info over and over again,
is because radiusd isn't sending back any accounting-response.

Here is the debug output, I do notice that the session time is zero and
radiusd doesn't like that, but would that prevent radiusd from sending back
an accounting-response?

rad_recv: Accounting-Request packet from host 198.88.216.4 port 1647,
id=141, length=278
        Event-Timestamp = "Jul 13 2007 17:12:30 CDT"
        User-Name = "spierce at illicom.net"
        NAS-IP-Address = 204.42.16.5
        NAS-Identifier = "tnt1.core.peoria.il.bwsys.net"
        NAS-Port = 1429
        NAS-Port-Type = Async
        Service-Type = Framed-User
        Acct-Status-Type = Stop
        Acct-Delay-Time = 13813416
        Acct-Session-Id = "439719181"
        Acct-Terminate-Cause = NAS-Request
        X-Ascend-Disconnect-Cause = Unknown
        X-Ascend-Connect-Progress = IPNCP-Opening
        X-Ascend-PreSession-Time = 27
        X-Ascend-Pre-Input-Octets = 352
        X-Ascend-Pre-Output-Octets = 349
        X-Ascend-Pre-Input-Packets = 11
        X-Ascend-Pre-Output-Packets = 13
        X-Ascend-Data-Rate = 28800
        X-Ascend-Xmit-Rate = 37333
        X-Ascend-Multilink-ID = 268768248
        X-Ascend-Num-In-Multilink = 0
        Acct-Link-Count = 1
        Acct-Multi-Session-Id = "100513f8"
        X-Ascend-Modem-PortNo = 31
        X-Ascend-Modem-SlotNo = 15
        X-Ascend-Modem-ShelfNo = 1
        X-Ascend-Data-Svc = Switched-Voice-Bearer
        Framed-Protocol = Ascend-MP
        Framed-IP-Address = 0.0.0.0
        Proxy-State = 0x3139
  Processing the preacct section of radiusd.conf
modcall:  entering group preacct for request 2209
rlm_acct_unique: WARNING: Attribute Client-IP-Address was not found in
request, unique ID MAY be inconsistent
rlm_acct_unique: Hashing 'NAS-Port = 1429,,NAS-IP-Address =
204.42.16.5,Acct-Session-Id = "439719181",User-Name = "spierce at illicom.net"'
rlm_acct_unique: Acct-Unique-Session-ID = "a9c91b40cff69183".
    rlm_realm: Looking up realm "illicom.net" for User-Name =
"spierce at illicom.net"
    rlm_realm: Found realm "illicom.net"
    rlm_realm: Adding Stripped-User-Name = "spierce"
    rlm_realm: Proxying request from user spierce to realm illicom.net
    rlm_realm: Adding Realm = "illicom.net"
    rlm_realm: Accounting realm is LOCAL.
    rlm_realm: Request already proxied.  Ignoring.
modcall: group preacct returns noop for request 2209
  Processing the accounting section of radiusd.conf
modcall:  entering group accounting for request 2209
radius_xlat:  'spierce'
rlm_sql (conxxus): sql_set_user escaped user --> 'spierce'
radius_xlat:  '                 update radacct             set AcctStopTime
= dateadd(second,-13813416,convert(datetime,'2007-12-20 12:16:43')),
AcctSessionTime = '',                   AcctStatusType = 'Stop',
AcctInputOctets = '0',                      AcctOutputOctets = '0',
AcctInputPackets = '0',                 AcctOutputPackets = '0',
AcctTerminateCause = 'NAS-Request',
AscendDisconnectCause = 'Unknown',                      AscendDataRate =
'28800',                   AscendXmitRate = '37333'                  where
AcctSessionId = '439719181'            and UserName = 'spierce'
and NASIPAddress = '204.42.16.5'                and isnull(AcctStopTime,0) =
0       '
rlm_sql (conxxus): Reserving sql socket id: 4
radius_xlat:  'stop packet with zero session length. [user
'spierce at illicom.net', nas '204.42.16.5']'
rlm_sql (conxxus) in sql_accounting: stop packet with zero session length.
[user 'spierce at illicom.net', nas '204.42.16.5']
rlm_sql (conxxus): Released sql socket id: 4
radius_xlat:  '                 insert into radacct
(AcctSessionId,                  AcctUniqueId,                   UserName,
Realm,                  NASIPAddress,                   NASIdentifier,
NASPort,           NASPortType,                     AcctStartTime,
AcctStopTime,                   AcctSessionTime,
AcctAuthentic,                   AcctStatusType,
AcctInputOctets,                        AcctOutputOctets,
AcctInputPackets,                        AcctOutputPackets,
AcctTerminateCause,                     AscendDisconnectCause,
AscendModemShelf,                       AscendModemSlot,
AscendModemPort,                    AscendDataRate,
AscendXmitRate,                 CalledStationId,
CallingStationId,                   ServiceType,
FramedProtocol,                 FramedIPAddress)                values
('439719181',                        'a9c91b40cff69183',
'spierce',                      'illicom.net',             '204.42.16.5',
'tnt1.core.peoria.il.bwsys.net',                        '1429',
'Async',           dateadd(second,-13813416-0,convert(datetime,'2007-12-20
12:16:43')),
dateadd(second,-13813416,convert(datetime,'2007-12-20 12:16:43')),
'',                     '',                     'Stop',                 '0',
'0',                     '0',                    '0',
'NAS-Request',                  'Unknown',                 '1',
'15',                   '31',                   '28800',
'37333',                   '',                    '',
'Framed-User',                  'Ascend-MP',                    '0.0.0.0')
'
rlm_sql (conxxus): Released sql socket id: 4
modcall: group accounting returns noop for request 2209
Finished request 2209
Going to the next request