[SR-Users] avp_radius load time high

Ricardo Martinez rmartinez at redvoiss.net
Fri Sep 14 15:42:24 CEST 2012 

Hi Daniel.

THanks for your answer.  Can you guide where to patch the radius module.
I’ve been looking the module misc_radius but I can’t find where to put more
debug…..

Thanks again.



Ricardo.-



*De:* Daniel-Constantin Mierla [mailto:miconda at gmail.com]
*Enviado el:* jueves, 13 de septiembre de 2012 16:19
*Para:* SIP Router - Kamailio (OpenSER) and SIP Express Router (SER) -
Users Mailing List
*CC:* Ricardo Martinez
*Asunto:* Re: [SR-Users] avp_radius load time high



Hello,

On 9/13/12 9:22 PM, Ricardo Martinez wrote:

Hello.

Could it be that this number maybe represent a failed connection to the
radius server?

I am not using radius myself, but I would try some generic troubleshooting:
- watch the network traffic between with the radius server to see if there
are delays between the requests and responses
- if you do also user authentication with radius, do you notice same
problems?
- if you run with debug 3, can you spot the delays in debug messages? Maybe
you can patch the radius module to print more log messages around the code
that sends the radius requests and waits for response

Cheers,
Daniel



Any help please?



Regards,

Ricardo.-





*De:* Ricardo Martinez [mailto:rmartinez at redvoiss.net]
*Enviado el:* miércoles, 12 de septiembre de 2012 22:43
*Para:* sr-users at lists.sip-router.org
*Asunto:* avp_radius load time high





Hello List.

I’m having the next issue.  I have a kamailio v3.2  working with a Radius
server as a backend.  All the INVITE’s coming to the kamailio server are
challenged against the Radius server and then avp_load is called from
Radius server too.   I’m having excessive delay times when I have near 10
call per second.  So I find it very weird.  I have made a benchmark for the
avp_load :

bm_start_timer("timer-tranum");

if( !radius_load_caller_avps("$fU@$fd")) {

sl_send_reply("403","Forbidden - Estamos experimentando problemas");

exit;

} else {

                    xlog("L_INFO", "[$ci]:[AUTH_REQUEST]: ok");

             };

bm_log_timer("timer-tranum");



and this is the output :



Sep 12 18:26:07  benchmark (timer timer-tranum [1]): 112441 [
msgs/total/min/max/avg - LR:
100/18446744073695545751/23297/18446744073709236247/184467440736955456.000000
| GB:
2100/18446744073708471097/10746/18446744073709542058/8784163844623081.000000]

Sep 12 18:26:55  benchmark (timer timer-tranum [1]): 280543 [
msgs/total/min/max/avg - LR:
100/10238598/21317/18446744073708596212/102385.980000 | GB:
2200/9158079/10746/18446744073709542058/4162.763182]

Sep 12 18:28:20  benchmark (timer timer-tranum [1]): 312902 [
msgs/total/min/max/avg - LR:
100/5048656/15491/18446744073709546022/50486.560000 | GB:
2300/14206735/10746/18446744073709546022/6176.841304]

Sep 12 18:28:31  benchmark (timer timer-tranum [1]): 301818 [
msgs/total/min/max/avg - LR:
100/5622716/43785/18446744073708810522/56227.160000 | GB:
2400/19829451/10746/18446744073709546022/8262.271250]

Sep 12 18:28:44  benchmark (timer timer-tranum [1]): 18446744073708689124 [
msgs/total/min/max/avg - LR:
100/1329199/59661/18446744073709167366/13291.990000 | GB:
2500/21158650/10746/18446744073709546022/8463.460000]

Sep 12 18:29:26  benchmark (timer timer-tranum [1]): 44160 [
msgs/total/min/max/avg - LR:
100/18446744073701320457/19576/18446744073708922050/184467440737013216.000000
| GB: 2600/12927491/10746/18446744073709546022/4972.111923]

Sep 12 18:30:18  benchmark (timer timer-tranum [1]): 218573 [
msgs/total/min/max/avg - LR:
100/18446744073707306935/17459/18446744073708876520/184467440737073056.000000
| GB: 2700/10682810/10746/18446744073709546022/3956.596296]

Sep 12 18:31:06  benchmark (timer timer-tranum [1]): 132751 [
msgs/total/min/max/avg - LR:
100/9611955/17057/18446744073708726431/96119.550000 | GB:
2800/20294765/10746/18446744073709546022/7248.130357]

Sep 12 18:31:55  benchmark (timer timer-tranum [1]): 18859 [
msgs/total/min/max/avg - LR:
100/18446744073705270953/18859/18446744073708747515/184467440737052704.000000
| GB: 2900/16014102/10746/18446744073709546022/5522.104138]

Sep 12 18:32:48  benchmark (timer timer-tranum [1]): 61954 [
msgs/total/min/max/avg - LR:
100/7124234/18036/18446744073708596768/71242.340000 | GB:
3000/23138336/10746/18446744073709546022/7712.778667]

Sep 12 18:33:39  benchmark (timer timer-tranum [1]): 53489 [
msgs/total/min/max/avg - LR:
100/18446744073707180114/18177/18446744073708790040/184467440737071808.000000
| GB: 3100/20766834/10746/18446744073709546022/6698.978710]

Sep 12 18:34:35  benchmark (timer timer-tranum [1]): 60604 [
msgs/total/min/max/avg - LR:
100/18446744073701108277/33983/18446744073708839351/184467440737011072.000000
| GB: 3200/12323495/10746/18446744073709546022/3851.092187]

Sep 12 18:35:24  benchmark (timer timer-tranum [1]): 54705 [
msgs/total/min/max/avg - LR:
100/7185862/15880/18446744073708608536/71858.620000 | GB:
3300/19509357/10746/18446744073709546022/5911.926364]

Sep 12 18:36:21  benchmark (timer timer-tranum [1]): 60356 [
msgs/total/min/max/avg - LR:
100/18446744073705699677/19466/18446744073708844524/184467440737056992.000000
| GB: 3400/15657418/10746/18446744073709546022/4605.122941]

Sep 12 18:37:14  benchmark (timer timer-tranum [1]): 18446744073708679441 [
msgs/total/min/max/avg - LR:
100/3506212/16102/18446744073708791471/35062.120000 | GB:
3500/19163630/10746/18446744073709546022/5475.322857]

Sep 12 18:38:07  benchmark (timer timer-tranum [1]): 93933 [
msgs/total/min/max/avg - LR:
100/18446744073704680760/17569/18446744073708741284/184467440737046816.000000
| GB: 3600/14292774/10746/18446744073709546022/3970.215000]

Sep 12 18:38:58  benchmark (timer timer-tranum [1]): 142777 [
msgs/total/min/max/avg - LR:
100/681953/15933/18446744073708708694/6819.530000 | GB:
3700/14974727/10746/18446744073709546022/4047.223514]

Sep 12 18:39:44  benchmark (timer timer-tranum [1]): 165921 [
msgs/total/min/max/avg - LR:
100/5153736/15749/18446744073708657622/51537.360000 | GB:
3800/20128463/10746/18446744073709546022/5296.963947]

Sep 12 18:40:31  benchmark (timer timer-tranum [1]): 127615 [
msgs/total/min/max/avg - LR:
100/18446744073709161716/20690/18446744073708793559/184467440737091616.000000
| GB: 3900/19738563/10746/18446744073709546022/5061.170000]

Sep 12 18:41:21  benchmark (timer timer-tranum [1]): 47762 [
msgs/total/min/max/avg - LR:
100/9001175/18909/18446744073708632234/90011.750000 | GB:
4000/28739738/10746/18446744073709546022/7184.934500]

Sep 12 18:42:11  benchmark (timer timer-tranum [1]): 62944 [
msgs/total/min/max/avg - LR:
100/18446744073708163674/17934/18446744073708825788/184467440737081632.000000
| GB: 4100/27351796/10746/18446744073709546022/6671.169756]





If the output is in microseconds and I have from time to time (for the last
100 messages) values like 184467440737081632.000000 microsecs

IS THIS NORMAL?

Then it backs to a more rational value : 90011.750000 microsecs.

I also made measures in my Radius Server and is answering with average time
around 0.01 secs.

So… what could it be the problem?.. Could be the RadiusClient-ng ?

In my attempt to solve the issue I increased the childrens for the kamailio
server from 16 to 60, but the problem persists.

Can someone help me here?



Best Regards,

Ricardo Martinez.-










_______________________________________________

SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list

sr-users at lists.sip-router.org

http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users



-- 

Daniel-Constantin Mierla - http://www.asipto.com

http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda

Kamailio Advanced Training, Berlin, Nov 5-8, 2012 - http://asipto.com/u/kat

Kamailio Advanced Training, Miami, USA, Nov 12-14, 2012 -
http://asipto.com/u/katu
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20120914/999b5a30/attachment-0001.htm>

More information about the sr-users mailing list