rlm_ippool: Deallocated entry for ip/port

[Akira Yata]

Hi all!

I have a problem with freeradius1.1 or NAS.
" Deallocated entry for ip/port " messages were shown in some users.
IP address that were shown as deallocated entry ip addresses were allocated
as framed-ip-address for differenet user, but some were not allocated as
framed-ip-address.

I'm searching cause of this problem for few days.
I'm sorry with my terrible English and silly query.

if you need more information plese let me know.
Any advice will help me,Thanks in advance.
debug log is below

Sending Accounting-Response of id 140 to 192.168.11.1 port 50016
rad_recv: Accounting-Request packet from host 192.168.11.1:50016, id=141,
length=247
        Acct-Status-Type = Stop
        User-Name = "aaa at .or.jp"
        Event-Timestamp = "Nov 22 2014 12:00:54 JST"
        Acct-Delay-Time = 3
        NAS-Identifier = "cisco"
        Acct-Session-Id = "erx FastEthernet 1/6.3:0:0029047976"
        NAS-IP-Address = 192.168.11.1
        Service-Type = Framed-User
        Calling-Station-Id = "#cisco#E16#0"
        Acct-Input-Gigawords = 0
        Acct-Input-Octets = 0
        Acct-Output-Gigawords = 0
        Acct-Output-Octets = 0
        ERX-Input-Gigapkts = 0
        Acct-Input-Packets = 0
        ERX-Output-Gigapkts = 0
        Acct-Output-Packets = 0
        NAS-Port-Type = Ethernet
        NAS-Port = 369098752
        NAS-Port-Id = "FastEthernet 1/6.3:0"
        Acct-Session-Time = 0
        Acct-Terminate-Cause = User-Error
rlm_radutmp: Logout for NAS 192.168.11.1 port 369098752, but no Login record
rlm_ippool: Searching for an entry for nas/port: 192.168.11.1/369098752
rlm_ippool: Deallocated entry for ip/port: 10.10.10.1/369098752
Sending Accounting-Response of id 142 to 192.168.11.1 port 50016
rad_recv: Accounting-Request packet from host 192.168.11.1:50016, id=143,
length=242
        Acct-Status-Type = Stop
        User-Name = "bbb at .or.jp"
        Event-Timestamp = "Nov 22 2014 12:00:54 JST"
        Acct-Delay-Time = 3
        NAS-Identifier = "cisco"
        Acct-Session-Id = "erx FastEthernet 1/6.3:0:0029047977"
        NAS-IP-Address = 192.168.11.1
        Service-Type = Framed-User
        Calling-Station-Id = "#cisco#E16#0"
        Acct-Input-Gigawords = 0
        Acct-Input-Octets = 0
        Acct-Output-Gigawords = 0
        Acct-Output-Octets = 0
        ERX-Input-Gigapkts = 0
        Acct-Input-Packets = 0
        ERX-Output-Gigapkts = 0
        Acct-Output-Packets = 0
        NAS-Port-Type = Ethernet
        NAS-Port = 369098752
        NAS-Port-Id = "FastEthernet 1/6.3:0"
        Acct-Session-Time = 0
        Acct-Terminate-Cause = User-Error
rlm_radutmp: Logout for NAS 192.168.11.1 port 369098752, but no Login record
rlm_ippool: Searching for an entry for nas/port: 192.168.11.1/369098752
rlm_ippool: Deallocated entry for ip/port: 10.10.10.1/369098752
Sending Accounting-Response of id 143 to 192.168.11.1 port 50016
rad_recv: Accounting-Request packet from host 192.168.11.1:50016, id=144,
length=240
        Acct-Status-Type = Stop
        User-Name = "ccc at .or.jp"
        Event-Timestamp = "Nov 22 2014 12:00:54 JST"
        Acct-Delay-Time = 3
        NAS-Identifier = "cisco"
        Acct-Session-Id = "erx FastEthernet 1/5:0029047978"
        NAS-IP-Address = 192.168.11.1
        Service-Type = Framed-User
        Calling-Station-Id = "#cisco#E15#0"
        Acct-Input-Gigawords = 0
        Acct-Input-Octets = 0
        Acct-Output-Gigawords = 0
        Acct-Output-Octets = 0
        ERX-Input-Gigapkts = 0
        Acct-Input-Packets = 0
        ERX-Output-Gigapkts = 0
        Acct-Output-Packets = 0
        NAS-Port-Type = Ethernet
        NAS-Port = 352321536
        NAS-Port-Id = "FastEthernet 1/5"
        Acct-Session-Time = 0
        Acct-Terminate-Cause = User-Error
rlm_radutmp: Logout for NAS 192.168.11.1 port 352321536, but no Login record
rlm_ippool: Searching for an entry for nas/port: 192.168.11.1/352321536
rlm_ippool: Deallocated entry for ip/port: 10.10.10.2/352321536
Sending Accounting-Response of id 144 to 192.168.11.1 port 50016
rad_recv: Accounting-Request packet from host 192.168.11.1:50016, id=145,
length=249
        Acct-Status-Type = Stop
        User-Name = "ddd at .or.jp"
        Event-Timestamp = "Nov 22 2014 12:00:54 JST"
        Acct-Delay-Time = 3
        NAS-Identifier = "cisco"
        Acct-Session-Id = "erx FastEthernet 1/6.2:102:0029047979"
        NAS-IP-Address = 192.168.11.1
        Service-Type = Framed-User
        Calling-Station-Id = "#cisco#E16#102"
        Acct-Input-Gigawords = 0
        Acct-Input-Octets = 0
        Acct-Output-Gigawords = 0
        Acct-Output-Octets = 0
        ERX-Input-Gigapkts = 0
        Acct-Input-Packets = 0
        ERX-Output-Gigapkts = 0
        Acct-Output-Packets = 0
        NAS-Port-Type = Ethernet
        NAS-Port = 369098854
        NAS-Port-Id = "FastEthernet 1/6.2:102"
        Acct-Session-Time = 0
        Acct-Terminate-Cause = User-Error
rlm_radutmp: Logout for NAS 192.168.11.1 port 369098854, but no Login record
rlm_ippool: Searching for an entry for nas/port: 192.168.11.1/369098854
rlm_ippool: Deallocated entry for ip/port: 210.229.182.164/369098854
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20141122/b557da5e/attachment-0001.html>