Re: isn´t registering FramedIpAdress (sometimes)!

[Ivan Kalik]

> Finally, i got the debug register:
>
> Going to the next request
> --- Walking the entire request list ---
> Waking up in 6 seconds...
> rad_recv: Accounting-Request packet from host XXX.XXX.236.1:59561, id=48,
> length=252
>         Service-Type = Framed-User
>         Framed-Protocol = PPP
>         NAS-Port = 1557370
>         NAS-Port-Type = Ethernet
>         User-Name = "test at test.com"
>         Calling-Station-Id = "00:10:10:AD:F7:7B"
>         Called-Station-Id = "PPPoE-Camping"
>         NAS-Port-Id = "EoIP->Camping"
>         MS-CHAP-Domain = "test.com"
>         Acct-Session-Id = "811025bf"
>         Framed-IP-Address = 192.168.101.249
>         Acct-Authentic = RADIUS
>         Event-Timestamp = "Oct 29 2009 18:33:36 AMST"
>         Acct-Session-Time = 95216
>         Acct-Input-Octets = 34048624
>         Acct-Input-Gigawords = 0
>         Acct-Input-Packets = 165487
>         Acct-Output-Octets = 190022963
>         Acct-Output-Gigawords = 0
>         Acct-Output-Packets = 214677
>         Acct-Status-Type = Stop
> 	Acct-Terminate-Cause = User-Request
>         NAS-Identifier = "lalala"
>         NAS-IP-Address = XXX.XXX.236.1
>         Acct-Delay-Time = 0
>         Mikrotik-Realm = "test.com"
>   Processing the preacct section of radiusd.conf
> modcall: entering group preacct for request 399
>   modcall[preacct]: module "preprocess" returns noop for request 399
> rlm_acct_unique: Hashing 'NAS-Port = 1557370,Client-IP-Address =
> XXX.XXX.236.1,NAS-IP-Address = XXX.XXX.236.1,Acct-Session-Id =
> "811025bf",User-Name = "test at test.com"'
> rlm_acct_unique: Acct-Unique-Session-ID = "38a543632a983ade".
>   modcall[preacct]: module "acct_unique" returns ok for request 399
>     rlm_realm: Looking up realm "evo.net.br" for User-Name =
> "test at test.com"
>     rlm_realm: No such realm "test.com"
>   modcall[preacct]: module "suffix" returns noop for request 399
> modcall: leaving group preacct (returns ok) for request 399
>   Processing the accounting section of radiusd.conf
> modcall: entering group accounting for request 399
> radius_xlat:  'test at test.com'
> rlm_sql (sql): sql_set_user escaped user --> 'test at test.com'
> radius_xlat:  '           UPDATE radacct SET              AcctStopTime
>   = '2009-10-29 18:33:36',              AcctSessionTime    = '95216',
>         AcctInputOctets    = '0' << 32 |
> '34048624',              AcctOutputOctets   = '0' << 32 |
>                  '190022963',              AcctTerminateCause =
> 'User-Request',              AcctStopDelay      = '0',
> ConnectInfo_stop   = ''           WHERE AcctSessionId   = '811025bf'
>     AND UserName          = 'test at test.com'           AND NASIPAddress
>  = 'XXX.XXX.236.1''
> rlm_sql (sql): Reserving sql socket id: 2
> rlm_sql (sql): Released sql socket id: 2
>   modcall[accounting]: module "sql" returns ok for request 399
>   modcall[accounting]: module "advertise" returns ok for request 399
> modcall: leaving group accounting (returns ok) for request 399
> Sending Accounting-Response of id 48 to XXX.XXX.236.1 port 59561
> Finished request 399
>
> Radacct:
> |    453321 | 811025bf      | 7b21431a8e07e896 | test at test.com |       |
> XXX.XXX.236.1 | 1557829   | Ethernet    | 2009-06-30 15:54:46 | 2009-10-29
> 18:33:36 |           95216 | RADIUS        |                   |
>        |        34048624 |        190022963 | PPPoE-Camping   |
> 00:10:10:AD:F7:7B | User-Request       | Framed-User | PPP            |
> 0.0.0.0         |              0 |             0 |                      |
>

Now find the start record for that session and see what happened to the
Framed-IP-Address there. Stop record doesn't update IP. Start and updates
do.

Ivan Kalik
Kalik Informatika ISP