[Kamailio-Users] strange fr_inv_timer behaviour

[hans at onsmail.nl]

I'm still trying to find out why the fr_inv_timer isn't working properly.

I tried version 1.5 with a very basic configuration and then the timeout is 
sent to early (about half the 'fr_inv_timer' value)
but when running version 1.4 (on the same machine) everything is working 
fine and the timeout occures after the amount of seconds defined by 
'fr_inv_timer'.

Anyone any idea what can cause this behavior in 1.5 (and not in 1.4) ?

Thanks
Hans


----- Original Message ----- 
From: <hans at onsmail.nl>
To: "Daniel-Constantin Mierla" <miconda at gmail.com>
Cc: <users at lists.kamailio.org>
Sent: Wednesday, June 10, 2009 20:10
Subject: Re: [Kamailio-Users] strange fr_inv_timer behaviour


> Daniel,
>
> loadmodule "tm.so"
> modparam("tm", "fr_inv_timer_avp", "$avp(s:callee_fr_inv_timer)")
> modparam("tm", "fr_timer", 3)
>
> $avp(s:callee_fr_inv_timer) = 90;
>
> This setting causes a 408 to be generated after 30 seconds.
>
> If i don't use the avp but set fr_inv_timer directly i have the same 
> problem.
>
> Kind regards
> Hans
>
>
>> Hello,
>>
>> can you send me all tm module parameters you set?
>>
>> I tried to reproduce, but all was fine:
>> - 40 sec t/o
>> INVITE: U 2009/06/10 12:52:54.457315 213.133.111.169:5062 ->
>> 86.121.130.133:6536
>> 408: U 2009/06/10 12:53:35.084385 213.133.111.169:5062 ->
>> 86.121.130.133:5060
>>
>> - 60 sec t/o
>> INVITE: U 2009/06/10 12:58:05.806196 213.133.111.169:5062 ->
>> 86.121.130.133:6536
>> 408: U 2009/06/10 12:59:05.644824 213.133.111.169:5062 ->
>> 86.121.130.133:5060
>>
>>
>> Thanks,
>> Daniel
>>
>> On 06/03/2009 05:00 PM, hans at onsmail.nl wrote:
>>> Hi,
>>>
>>> When setting ?fr_inv_timer? to 60s and the phone is not answered
>>> kamailio
>>> generates a timeout after 20s ? If ?fr_inv_timer? is set to 90s the
>>> timeout is generated after 30s ?
>>>
>>> Doing a level 7 debug I received the following tm timer related output
>>>
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1755]:
>>> DBG:tm:set_timer: relative timeout is 500000
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1755]:
>>> DBG:tm:insert_timer_unsafe: [4]: 0xafa6abc4 (16900000)
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1755]:
>>> DBG:tm:set_timer: relative timeout is 3
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1755]:
>>> DBG:tm:insert_timer_unsafe: [0]: 0xafa6abe0 (51)
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1754]:
>>> DBG:tm:set_timer: relative timeout is 60
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1754]:
>>> DBG:tm:insert_timer_unsafe: [1]: 0xafa6abe0 (108)
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1751]:
>>> DBG:tm:set_timer: relative timeout is 60
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1751]:
>>> DBG:tm:insert_timer_unsafe: [1]: 0xafa6abe0 (108)
>>> Jun  3 15:07:12 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:utimer_routine: timer routine:4,tl=0xafa6abc4 next=(nil),
>>> timeout=16900000
>>>
>>> <phone is ringing ....>
>>>
>>> Jun  3 15:07:32 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:timer_routine: timer routine:1,tl=0xafa6abe0 next=(nil),
>>> timeout=108
>>> Jun  3 15:07:32 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:final_response_handler: stop retr. and send CANCEL (0xafa6aa78)
>>> Jun  3 15:07:32 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:t_should_relay_response: T_code=180, new_code=408
>>> Jun  3 15:07:32 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:t_pick_branch: picked branch 0, code 408
>>> Jun  3 15:07:32 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:t_check: start=0xafa6aa78
>>> Jun  3 15:07:32 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:t_check: transaction already found!
>>> Jun  3 15:07:32 sip-proxy-01 /usr/local/sbin/kamailio[1758]:
>>> DBG:tm:pv_get_tm_reply_code: reply code is <408>
>>>
>>> version: kamailio 1.5.1-notls (i386/linux)
>>> flags: STATISTICS, USE_IPV6, USE_TCP, DISABLE_NAGLE, USE_MCAST,
>>> SHM_MMAP,
>>> PKG_MALLOC, F_MALLOC, FAST_LOCK-ADAPTIVE_WAIT
>>> ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144, MAX_LISTEN 16,
>>> MAX_URI_SIZE 1024, BUF_SIZE 65535, PKG_SIZE 4194304
>>> poll method support: poll, epoll_lt, epoll_et, sigio_rt, select.
>>> svnrevision: 2:5868M
>>> @(#) $Id: main.c 5608 2009-02-13 16:48:17Z henningw $
>>> main.c compiled on 14:40:00 Jun  3 2009 with gcc 4.1.2
>>>
>>> Kind regards
>>> Hans
>>>
>>>
>>>
>>> _______________________________________________
>>> Kamailio (OpenSER) - Users mailing list
>>> Users at lists.kamailio.org
>>> http://lists.kamailio.org/cgi-bin/mailman/listinfo/users
>>> http://lists.openser-project.org/cgi-bin/mailman/listinfo/users
>>>
>>>
>>
>> --
>> Daniel-Constantin Mierla
>> http://www.asipto.com/
>>
>>
>
>
>
> _______________________________________________
> Kamailio (OpenSER) - Users mailing list
> Users at lists.kamailio.org
> http://lists.kamailio.org/cgi-bin/mailman/listinfo/users
> http://lists.openser-project.org/cgi-bin/mailman/listinfo/users