Выдаёт одни и те же адреса из пула
Добавлено: Вс ноя 23, 2008 11:46 am
Добрый день.
Пул в абиллсе на 200 IP.
При подключении нового пользователя с некоторой долей вероятности выдаётся IP из уже работающих пользователей.
Вот кусок радиус дебага:
Вот пришёл alive от пользователя который уже час работает:
Sun Nov 23 14:15:23 2008
rad_recv: Accounting-Request packet from host 127.0.0.1:49962, id=198, length=216
Service-Type = VPN
User-Name = "i21192"
Framed-Protocol = PPP
Framed-IP-Address = 192.168.100.113
Framed-IP-Netmask = 0.0.0.0
NAS-Identifier = "serv.izm.local"
NAS-Port = 48
Acct-Status-Type = Interim-Update
Acct-Session-Id = "s-1761715782"
Acct-Multi-Session-Id = ""
Acct-Delay-Time = 0
Acct-Input-Octets = 7712924
Acct-Input-Packets = 16286
Acct-Output-Octets = 10661068
Acct-Output-Packets = 17971
Acct-Session-Time = 8562
Exppp-Acct-LocalInput-Octets = 0
Exppp-Acct-LocalOutput-Octets = 0
Exppp-Acct-IteriumIn-Octets = 16192
Exppp-Acct-IteriumOut-Octets = 1571
Exppp-Acct-LocalIteriumIn-Octets = 0
Exppp-Acct-LocalIteriumOut-Octets = 0
Exppp-Acct-IteriumTime = 61
А вот подключается новый юзер и ему присваеватся такой же IP и он соотвественно отваливается с ошибкой о невозможности создать маршрут:
Sun Nov 23 14:16:03 2008 : Debug: rad_rmspace_pair: User-Name now 'test'
Sun Nov 23 14:16:03 2008 : Debug: Processing the authorize section of radiusd.conf
Sun Nov 23 14:16:03 2008 : Debug: modcall: entering group authorize for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling preprocess (rlm_preprocess) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from preprocess (rlm_preprocess) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "preprocess" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling auth_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: radius_xlat: '/var/log/radacct/127.0.0.1/auth-detail-20081123'
Sun Nov 23 14:16:03 2008 : Debug: rlm_detail: /var/log/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radacct/127.0.0.1/auth-detail-200
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from auth_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "auth_log" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling mschap (rlm_mschap) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from mschap (rlm_mschap) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "mschap" returns noop for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling perl (rlm_perl) for request 51
Sun Nov 23 14:16:03 2008 : Debug: Using perl at 0x80d3ae0
Auth-Type := Accept
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Acct-Interim-Interval = 60
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Session-Timeout = 639837
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Exppp-Traffic-Limit = 4294000000
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Framed-IP-Address = 192.168.100.113
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Framed-IP-Netmask = 0.0.0.0
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Exppp-LocalTraffic-Limit = 4288675840
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Auth-Type = Accept
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from perl (rlm_perl) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "perl" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall: leaving group authorize (returns ok) for request 51
Sun Nov 23 14:16:03 2008 : Debug: rad_check_password: Found Auth-Type Accept
Sun Nov 23 14:16:03 2008 : Debug: rad_check_password: Auth-Type = Accept, accepting the user
Sun Nov 23 14:16:03 2008 : Auth: Login OK: [test/<CHAP-Password>] (from client localhost port 0)
Sun Nov 23 14:16:03 2008 : Debug: Processing the post-auth section of radiusd.conf
Sun Nov 23 14:16:03 2008 : Debug: modcall: entering group post-auth for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[post-auth]: calling reply_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: radius_xlat: '/var/log/radacct/127.0.0.1/reply-detail-20081123'
Sun Nov 23 14:16:03 2008 : Debug: rlm_detail: /var/log/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to /var/log/radacct/127.0.0.1/reply-detail-2
Sun Nov 23 14:16:03 2008 : Debug: modsingle[post-auth]: returned from reply_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[post-auth]: module "reply_log" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall: leaving group post-auth (returns ok) for request 51
Sending Access-Accept of id 43 to 127.0.0.1 port 62974
Acct-Interim-Interval = 60
Session-Timeout = 639837
Exppp-Traffic-Limit = 4294000000
Framed-IP-Address = 192.168.100.113
Framed-IP-Netmask = 0.0.0.0
Exppp-LocalTraffic-Limit = 4288675840
Проблема тем острее, чем больше юзеров в онлайне.
Помогите, куда дальше смотреть?
Abills 0.4 от сентября 2008г.
ExPPP
FreeBSD 6.3
Пул в абиллсе на 200 IP.
При подключении нового пользователя с некоторой долей вероятности выдаётся IP из уже работающих пользователей.
Вот кусок радиус дебага:
Вот пришёл alive от пользователя который уже час работает:
Sun Nov 23 14:15:23 2008
rad_recv: Accounting-Request packet from host 127.0.0.1:49962, id=198, length=216
Service-Type = VPN
User-Name = "i21192"
Framed-Protocol = PPP
Framed-IP-Address = 192.168.100.113
Framed-IP-Netmask = 0.0.0.0
NAS-Identifier = "serv.izm.local"
NAS-Port = 48
Acct-Status-Type = Interim-Update
Acct-Session-Id = "s-1761715782"
Acct-Multi-Session-Id = ""
Acct-Delay-Time = 0
Acct-Input-Octets = 7712924
Acct-Input-Packets = 16286
Acct-Output-Octets = 10661068
Acct-Output-Packets = 17971
Acct-Session-Time = 8562
Exppp-Acct-LocalInput-Octets = 0
Exppp-Acct-LocalOutput-Octets = 0
Exppp-Acct-IteriumIn-Octets = 16192
Exppp-Acct-IteriumOut-Octets = 1571
Exppp-Acct-LocalIteriumIn-Octets = 0
Exppp-Acct-LocalIteriumOut-Octets = 0
Exppp-Acct-IteriumTime = 61
А вот подключается новый юзер и ему присваеватся такой же IP и он соотвественно отваливается с ошибкой о невозможности создать маршрут:
Sun Nov 23 14:16:03 2008 : Debug: rad_rmspace_pair: User-Name now 'test'
Sun Nov 23 14:16:03 2008 : Debug: Processing the authorize section of radiusd.conf
Sun Nov 23 14:16:03 2008 : Debug: modcall: entering group authorize for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling preprocess (rlm_preprocess) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from preprocess (rlm_preprocess) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "preprocess" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling auth_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: radius_xlat: '/var/log/radacct/127.0.0.1/auth-detail-20081123'
Sun Nov 23 14:16:03 2008 : Debug: rlm_detail: /var/log/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radacct/127.0.0.1/auth-detail-200
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from auth_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "auth_log" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling mschap (rlm_mschap) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from mschap (rlm_mschap) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "mschap" returns noop for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: calling perl (rlm_perl) for request 51
Sun Nov 23 14:16:03 2008 : Debug: Using perl at 0x80d3ae0
Auth-Type := Accept
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Acct-Interim-Interval = 60
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Session-Timeout = 639837
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Exppp-Traffic-Limit = 4294000000
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Framed-IP-Address = 192.168.100.113
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Framed-IP-Netmask = 0.0.0.0
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Exppp-LocalTraffic-Limit = 4288675840
Sun Nov 23 14:16:03 2008 : Debug: rlm_perl: Added pair Auth-Type = Accept
Sun Nov 23 14:16:03 2008 : Debug: modsingle[authorize]: returned from perl (rlm_perl) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[authorize]: module "perl" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall: leaving group authorize (returns ok) for request 51
Sun Nov 23 14:16:03 2008 : Debug: rad_check_password: Found Auth-Type Accept
Sun Nov 23 14:16:03 2008 : Debug: rad_check_password: Auth-Type = Accept, accepting the user
Sun Nov 23 14:16:03 2008 : Auth: Login OK: [test/<CHAP-Password>] (from client localhost port 0)
Sun Nov 23 14:16:03 2008 : Debug: Processing the post-auth section of radiusd.conf
Sun Nov 23 14:16:03 2008 : Debug: modcall: entering group post-auth for request 51
Sun Nov 23 14:16:03 2008 : Debug: modsingle[post-auth]: calling reply_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: radius_xlat: '/var/log/radacct/127.0.0.1/reply-detail-20081123'
Sun Nov 23 14:16:03 2008 : Debug: rlm_detail: /var/log/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to /var/log/radacct/127.0.0.1/reply-detail-2
Sun Nov 23 14:16:03 2008 : Debug: modsingle[post-auth]: returned from reply_log (rlm_detail) for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall[post-auth]: module "reply_log" returns ok for request 51
Sun Nov 23 14:16:03 2008 : Debug: modcall: leaving group post-auth (returns ok) for request 51
Sending Access-Accept of id 43 to 127.0.0.1 port 62974
Acct-Interim-Interval = 60
Session-Timeout = 639837
Exppp-Traffic-Limit = 4294000000
Framed-IP-Address = 192.168.100.113
Framed-IP-Netmask = 0.0.0.0
Exppp-LocalTraffic-Limit = 4288675840
Проблема тем острее, чем больше юзеров в онлайне.
Помогите, куда дальше смотреть?
Abills 0.4 от сентября 2008г.
ExPPP
FreeBSD 6.3