[c-nsp] C7200 and AAA Accounting

[Chris Knipe]

Hi Guys,

I'm hoping that someone can assist in debugging something rather strange.  I
have a 7206 (NPE-G1) terminating PPPoEoE sessions.  AAA is working fine and
Authentication as well as Authorization happens as expected.  However, for
some reason, the 7200 refuses to send any Accounting information.  I'm sure
this must be something stupid and small that I am overlooking - hopefully a
fresh pair of eyes will spot what I'm failing to see! :-)

Version:
Cisco IOS Software, 7200 Software (C7200-ADVSECURITYK9-M), Version
15.2(4)M4, RELEASE SOFTWARE (fc2)

Relevant configurations:
aaa new-model
aaa session-mib disconnect
aaa group server radius MYRADIUS
 server x.x.x.43 auth-port 1812 acct-port 1813
 ip radius source-interface Loopback0
 attribute nas-port format a
 load-balance method least-outstanding
 mac-delimiter colon
aaa authentication login MYRADIUS group radius local
aaa authentication ppp default group MYRADIUS
aaa authorization exec MYRADIUS group radius local 
aaa authorization network default group MYRADIUS 
aaa accounting send stop-record always
aaa accounting delay-start
aaa accounting session-duration ntp-adjusted
aaa accounting update newinfo periodic 30
aaa accounting network default start-stop group MYRADIUS
aaa nas port extended
aaa session-id common

radius-server attribute 44 extend-with-addr
radius-server attribute 6 mandatory
radius-server attribute 32 include-in-access-req 
radius-server attribute 32 include-in-accounting-req 
radius-server attribute nas-port format b
radius-server attribute 61 extended
radius-server attribute 31 mac format ietf upper-case
radius-server host x.x.x.43 auth-port 1812 acct-port 1813 key 7 a
radius-server retransmit 2
radius-server timeout 10
radius-server unique-ident 5
radius-server load-balance method least-outstanding

debug aaa accounting:
000279: Apr  5 12:12:09.718: AAA/ACCT/CLIENT(0000001A): recv 1000000000bps
xmit 1000000000bps
000280: Apr  5 12:12:09.718: AAA/ACCT/HC(0000001A): Register PPPoE/5B00001C
64 bit counter support not configured
000281: Apr  5 12:12:09.718: AAA/ACCT/HC(0000001A): Update PPPoE/5B00001C 
000282: Apr  5 12:12:09.718: AAA/ACCT/HC(0000001A): no HC PPPoE/5B00001C 
000283: Apr  5 12:12:09.718: AAA/ACCT/EVENT/(0000001A): CALL START
000284: Apr  5 12:12:09.718: Getting session id for NET(0000001A) :
db=6AB5C8B8
000285: Apr  5 12:12:09.718: AAA/ACCT(00000000): add node, session 215
000286: Apr  5 12:12:09.718: AAA/ACCT/NET(0000001A): add, count 1
000287: Apr  5 12:12:09.718: AAA/ACCT/NET(0000001A): Pick method list
'default'
000288: Apr  5 12:12:09.718: AAA/ACCT/SETMLIST(0000001A): Handle 0, mlist
6A148168, Name default
000289: Apr  5 12:12:09.718: AAA/ACCT/EVENT/(0000001A): ATTR REPLACE
000290: Apr  5 12:12:09.718: AAA/ACCT(0000001A): Accounting response status
= FAILURE
000291: Apr  5 12:12:09.718: AAA/ACCT(0000001A): Send NEWINFO accounting
notification to EM successfully
000292: Apr  5 12:12:09.718: AAA/ACCT/EVENT/(0000001A): ATTR REPLACE
000293: Apr  5 12:12:09.718: AAA/ACCT/EVENT/(0000001A): ATTR REPLACE
000294: Apr  5 12:12:09.838: Getting session id for NET(0000001A) :
db=6AB5C8B8
000295: Apr  5 12:12:10.842: Getting session id for NET(0000001A) :
db=6AB5C8B8
000296: Apr  5 12:12:10.850: AAA/ACCT/NET(0000001A): Pick method list
'default'
000297: Apr  5 12:12:10.850: AAA/ACCT/SETMLIST(0000001A): Handle 0, mlist
6A148168, Name default
000298: Apr  5 12:12:10.850: AAA/ACCT/EVENT/(0000001A): NET UP
000299: Apr  5 12:12:10.850: AAA/ACCT/CLIENT(0000001A): recv 1000000000bps
xmit 1000000000bps
000300: Apr  5 12:12:10.850: AAA/ACCT/HC(0000001A): Update PPPoE/5B00001C 
000301: Apr  5 12:12:10.850: AAA/ACCT/HC(0000001A): no HC PPPoE/5B00001C 
000302: Apr  5 12:12:10.862: AAA/ACCT/EVENT/(0000001A): IPCP_PASS
000303: Apr  5 12:12:10.862: AAA/ACCT/NET(0000001A): Queueing record is
START
000304: Apr  5 12:12:10.862: AAA/ACCT(0000001A): Accounting method=MYRADIUS
(RADIUS)
000305: Apr  5 12:12:10.862: AAA/ACCT/NET(0000001A): Suppressed record  <<<
Accounting supressed and not sent.
000306: Apr  5 12:12:10.862: AAA/ACCT(0000001A): mlist_periodic is not set,
interval 0
000307: Apr  5 12:12:10.862: AAA/ACCT(0000001A): Resetting Periodic timer
600

Many thanks,
Chris.