[SR-Users] E2E ACK is not logged with siptrace modul kamailio

Daniel-Constantin Mierla miconda at gmail.com
Thu Aug 11 20:41:30 CEST 2011


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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20110811/031465bb/attachment-0001.htm>


More information about the sr-users mailing list