[SR-Users] E2E ACK is not logged with siptrace modul kamailio
Daniel-Constantin Mierla
miconda at gmail.com
Wed Aug 17 12:09:58 CEST 2011
Hello,
I have committed the feature to be able to use sip_trace() from
onsend_route, therefore you can catch forwarded ACKs:
http://git.sip-router.org/cgi-bin/gitweb.cgi/sip-router/?a=commit;h=9d8df2527d13878cee23af5b14f142a0cc4cd878
An example of usage would be:
onsend_route {
if(method==ACK") sip_trace();
}
Let me know if it works.
Cheers,
Daniel
On 8/13/11 9:46 PM, Mészáros Mihály wrote:
> Hi Daniel,
>
> Thank you for looking after the issue.
> Am I understand you correctly:
> You mean that it couldn't be corrected (or it is not the right
> solution) in TM to have a callback to messages what are sent out
> stateless way, and correct the issue in this way.
> So you mean I will have to add siptrace to call logic in config
> file to route block onsend_route?
>
> I am looking forward to hear about the fix, after you will be ready
> with it.
>
> Many Thanks,
> Misi
>
> On 2011-08-12 15:25, Daniel-Constantin Mierla wrote:
>> Hello,
>>
>> ok, seems that there is no actual callback for outgoing ACK -- the
>> same seems to be in 1.x, based on a quick look there -- the callback
>> for TM outgoing requests is useless since the ACK is forwarded in
>> stateless mode.
>>
>> The great thing with 3.x is that we have onsend_route where the
>> outgoing message content is available, so I just need to code a bit
>> in siptrace module and have it working properly to store the outgoing
>> ACKs. I will try to do it asap.
>>
>> Cheers,
>> Daniel
>>
>> On 8/11/11 9:23 PM, MÉSZÁROS Mihály wrote:
>>> Hello Daniel,
>>>
>>> I appreciate highly your help!
>>>
>>> Cheers,
>>> Misi
>>>
>>> 2011-08-11 20:41 keltezéssel, Daniel-Constantin Mierla írta:
>>>> Hello,
>>>>
>>>> I will look to see what happens with the outgoing ACK, probably
>>>> tomorrow or so. Btw, there is no need to apologize, here are open
>>>> discussions, reporting issues, throwing ideas, bad or good, the
>>>> conclusion comes after some analysis and help from the others. It's
>>>> hard to know everything and who does not care does not participate.
>>>>
>>>> Cheers,
>>>> Daniel
>>>>
>>>> On 8/11/11 4:32 PM, MÉSZÁROS Mihály wrote:
>>>>> Hi Daniel,
>>>>>
>>>>> I put on setflag before WITHINDLG,
>>>>> so AFAIK the ACK as a new transaction is executing this step.
>>>>> The root of my problem is that I can see the incoming E2E ACK, but
>>>>> i couldn't see the "outgoing"!
>>>>>
>>>>> route {
>>>>> #pike flood detection
>>>>> if (!pike_check_req()) { exit; };
>>>>>
>>>>> # per request initial checks
>>>>> route(REQINIT);
>>>>>
>>>>> #siptrace
>>>>> setflag(22);
>>>>> sip_trace();
>>>>>
>>>>> xdbg("SIP Request: \n $mb \n");
>>>>>
>>>>> # NAT detection
>>>>> route(NAT);
>>>>>
>>>>> # handle requests within SIP dialogs
>>>>> route(WITHINDLG);
>>>>>
>>>>>
>>>>> And in debug i can't find where want kamailio log/trace the
>>>>> _outgoing_ message.
>>>>> I can see only that it will be forwarded in stateless way.
>>>>> This is why i started to dig in sl modul. But thank You, now i see
>>>>> it was a mistake. sorrrrrrrry.
>>>>>
>>>>> Sorry for the early case opening, you are right not sl causing
>>>>> that. I thought i find something. sorry again.
>>>>>
>>>>> But i can see only incoming E2E ACK and I can't see the outgoing
>>>>> pair in siptrace.
>>>>> In TM modul i can't see where it is calling back siptrace in case
>>>>> of E2E ack.
>>>>>
>>>>>
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [socket_info.c:501]: grep_sock_info - checking if host==us:
>>>>> 14==14 && [195.11
>>>>> 1.192.14] == [195.111.192.14]
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [socket_info.c:504]: grep_sock_info - checking if port 5061
>>>>> matches port 5061
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [parser/msg_parser.c:103]: found end of header
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: rr
>>>>> [loose.c:257]: No next Route HF found
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: rr
>>>>> [loose.c:834]: no next URI found
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: tm
>>>>> [t_lookup.c:1379]: DEBUG: t_newtran: msg id=68 , global msg
>>>>> id=67 , T on entrance=
>>>>> 0xffffffff
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: tm
>>>>> [t_lookup.c:528]: t_lookup_request: start searching:
>>>>> hash=63155, isACK=1
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: tm
>>>>> [t_lookup.c:564]: DEBUG: proceeding to pre-RFC3261 transaction
>>>>> matching
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: tm
>>>>> [t_lookup.c:720]: DEBUG: t_lookup_request: e2e proxy ACK found
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: tm
>>>>> [t_reply.c:301]: DEBUG: totag for e2e ACK found: 0
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: tm
>>>>> [t_hooks.c:288]: DBG: trans=0xb2954a38, callback type 4, id 0
>>>>> entered
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: tm
>>>>> [t_funcs.c:315]: SER: forwarding ACK statelessly
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [msg_translator.c:2437]: create_via_hf: id added: <;i=01>, rcv
>>>>> proto=2
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [msg_translator.c:204]: check_via_address(195.111.192.7,
>>>>> 195.111.192.7, 0)
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [forward.c:599]: Sending: ACK
>>>>> sip:193.225.95.130:5061;transport=tls SIP/2.0^M
>>>>> Via: SIP/2.0/TLS 195.111.192.14:5061;branch=0;i=01^M Via:
>>>>> SIP/2.0/TCP 195.111.192.7;branch=0;i=5fb^M Via: SIP/2.0/TLS
>>>>> 193.6.222.62:5061;bra
>>>>> nch=z9hG4bK2619604118-1823^M Max-Forwards: 68^M
>>>>> Proxy-Authorization: Digest
>>>>> username="niif-tartalek",realm="195.111.192.7",nonce="TkOk+05Do
>>>>> 8/4J70CauQqX2GiwryZl/Do",uri="sip:9999 at rmx.vvc.niif.hu",response="00e9a9991a2f93c44c25de309022cee8",algorithm=md5^M
>>>>> From: niif-tartalek <si
>>>>> p:niif-tartalek at 195.111.192.7>;tag=plcm_2618225217-1823;epid=8210210CA530CG^M
>>>>> To: <sip:9999 at rmx.vvc.niif.hu>;tag=rmx2k_2618766049-17745-RMX
>>>>> -0000000006-0080871424^M Call-ID: 2618224581-1823^M CSeq: 2
>>>>> ACK^M Contact: niif-tartalek
>>>>> <sip:niif-tartalek at 193.6.222.62:5061;transport=tls
>>>>> >;proxy=replace;+sip.instance="<urn:uuid:615fd9bb-1986-52eb-9084-681c290b7e3a>"^M
>>>>> User-Agent:Polycom HDX 8000 HD (Release - 3.0.2-11176)^M
>>>>> Supported: ms-forking^M Content-Length: 0^M ^M .
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [forward.c:601]: orig. len=973, new_len=918, proto=3
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [tcp_main.c:2299]: tcp_send: found fd in cache ( 11,
>>>>> 0xb2922e88, 18)
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [tcp_main.c:2522]: tcp_send: sending...
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [tcp_main.c:2556]: tcp_send: after real write: c= 0xb2922e88
>>>>> n=949 fd=11
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [tcp_main.c:2557]: tcp_send: buf=
>>>>> ^W^C^A^C<B0>@<B7>*<D9>^O<B5>-<D2>j<F9>N`<87>
>>>>> lj<F4><8B><B5>B<CE>^VC^_V<FF><F4><D4>h'<ED><CE>sU<92><F8><84>]'<AD>**<EE>H;<94>]'2|<9F><C8>^FNu<CB>!<B0><B6><FC>Y2!<9A>
>>>>> Ue(<E3><9E>M<88>x<8A>
>>>>> <C1><EF><DD>^X<A4><A3>^A<D4><D0>¬f<B1><B0><B9>y^G^]
>>>>> <85>?<86>@<AB><AB>We<C5><EF>4i
>>>>> <84>^B<F2>kf^M<8E><<A7>^\<F3><D5>^U5<AE><AB><84><84><EB>
>>>>> <A2>+V<9A>^R<9E><90>_p<AB>^F^Z<A3>i<E0><D0>^E??<CE>^D6LN4L<82><FE>^E<EA>p<F0>6u<A3>VT<94>C9<A7>d[dL1^D<BF><86><8F>3^M<BB><96>S<A8><A3>^^?kO
>>>>> <F7>}Q;<<E6>M<A9>N,AE-F<9A><AE><A1>g<FB>w<95>
>>>>> ?^U^Q_<EF><E2><8F><EC>m&<F3>^^<EF><F4>^[<B8><91>e9#<A7>(<82>^Y<EF><83>^O{<8B><A3><B3>(K_
>>>>> <EA>-
>>>>> <CE>^F<E3>T<DD><FA><B6><C6><C0>R<B6><AE>mhs^T^G<8F><FF><E9><<F8><8A>|i<A3>?
>>>>> qf<C3>^^G7<C7>ub<F0>&^R^D<C7>^R?<91><BC>uV<9D><F5>^[<F5><AF>w6
>>>>> <93><98>`<B5>t7D<B1><E1><C5>^\<E3>6O<FC><84><9F><F1>y<E8>!v<9F><C1>;<AD><AA>x<AA><B6>^Z<A6>P<8E>Y<BA>^\<9D><A2><A6><A6><9E>3}70^K<CC>-<EA>h
>>>>> <DB>^R^O2<D7>H+m:<A4><F5>??<BA><9B>^\<AD><B1><85><DF>c<FE><U+05FA><C0>,<87>a&^Z^U^V<A6>4<B0><A8>?<A9>?<F5>3<9E><D1>^[<E7><9D><F7>+<90>P<DA>
>>>>> <E7>^^<D3>WO<ED><97><EF><B4>1<BA>?1<B7><B1><9F>X
>>>>> Aug 11 11:42:06 hal /usr/sbin/kamailio[24381]: DEBUG: <core>
>>>>> [usr_avp.c:646]: DEBUG:destroy_avp_list: destroying list (nil)
>>>>>
>>>>>
>>>>> Thanks,
>>>>> Misi
>>>>>
>>>>> 2011-08-11 15:38 keltezéssel, Daniel-Constantin Mierla írta:
>>>>>> Hello,
>>>>>>
>>>>>> E2E ACK is getting to configuration file routing block. With the
>>>>>> latest stable version default config file, it is handled in the
>>>>>> route[WITHINDLG]. Be sure you call sip trace function there. This
>>>>>> will capture the incoming E2E ACK. For outgoing version, set the
>>>>>> flag and it should be captured in TM callback, iirc.
>>>>>>
>>>>>> SL module is filtering the ACKs that are a result of negative
>>>>>> replies sent with SL module.
>>>>>>
>>>>>> Cheers,
>>>>>> Daniel
>>>>>>
>>>>>> On Thu, Aug 11, 2011 at 3:29 PM, MÉSZÁROS Mihály <misi at niif.hu
>>>>>> <mailto:misi at niif.hu>> wrote:
>>>>>>
>>>>>> Hi,
>>>>>>
>>>>>> I want to see all incoming and outgoing sip messages in sip
>>>>>> trace table, but i am missing messages.
>>>>>> I am using TLS so i can't see really trace the conversation
>>>>>> only this way.
>>>>>>
>>>>>> I am wondering if it is know limitation, or an issue:
>>>>>> I am experiencing that E2E ACK is not logged in siptrace table.
>>>>>>
>>>>>> I opened a ticket with the details:
>>>>>> http://sip-router.org/tracker/index.php?do=details&task_id=144&project=1&pagenum=1
>>>>>> <http://sip-router.org/tracker/index.php?do=details&task_id=144&project=1&pagenum=1>
>>>>>>
>>>>>> Many Thanks,
>>>>>> Misi
>>>>>>
>>>>>> _______________________________________________
>>>>>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users
>>>>>> mailing list
>>>>>> sr-users at lists.sip-router.org
>>>>>> <mailto: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
>>>>>>
>>>>>>
>>>>>> _______________________________________________
>>>>>> 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
>>>>>
>>>>>
>>>>>
>>>>> _______________________________________________
>>>>> 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
>>>> Kamailio Advanced Training, Oct 10-13, Berlin:http://asipto.com/u/kat
>>>> http://linkedin.com/in/miconda -- http://twitter.com/miconda
>>>
>>>
>>>
>>> _______________________________________________
>>> 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
>> Kamailio Advanced Training, Oct 10-13, Berlin:http://asipto.com/u/kat
>> http://linkedin.com/in/miconda -- http://twitter.com/miconda
>
>
>
> _______________________________________________
> 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
Kamailio Advanced Training, Oct 10-13, Berlin: http://asipto.com/u/kat
http://linkedin.com/in/miconda -- http://twitter.com/miconda
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20110817/8683ee53/attachment-0001.htm>
More information about the sr-users
mailing list