sr-dev September 2014

sr-dev@lists.kamailio.org

33 participants
376 discussions

Re: [sr-dev] sr-dev Digest, Vol 71, Issue 70
by varun pratapsingh 20 Sep '14

20 Sep '14

HI Shehzad, First of all thanks to you for writing such a nice module. I am also facing the same problem. The module works fine when UAC (PJSIP) sends the encoded SIP packet on UDP transport but when it sends the same encoded packet with TCP transport it never reaches to the nio_msg_received() function in corex_nio.c and so does not print anything in $mb when printed from the kamailio.cfg using the xlog in event_route[network:msg]. Digging further I also found that when kamailio receives the TCP packet with encoded sip in file tcp_read.c in function tcp_read_headers() then it goes for some tcp_headers parsing and drops the packet. But it works good when the TCP packet data segment is not encoded that is SIP packet is not encoded and transmitted on TCP : 1) TCP Headers + payload (SIP Packet) ----> this case works fine 2) TCP Headers + payload (encoded SIP Packet) ------> this case does not work fine. Any pointers where I might be wrong. Thanks Varun Regards Varun > Date: Sat, 20 Sep 2014 19:12:24 +0200 > From: Muhammad Shahzad <shaheryarkh(a)gmail.com> > To: "Kamailio (SER) - Development Mailing List" > <sr-dev(a)lists.sip-router.org> > Subject: Re: [sr-dev] Reqd. help on Corex (Obfuscate) - Kamailio 4.2.x > Message-ID: > < > CAFZQphwxr6TbVV98tF5bGAcfi7822POMGGhadP4_x77XZw+hDg(a)mail.gmail.com> > Content-Type: text/plain; charset="utf-8" > > There is something wrong with your encryption setup, the event_route > [network:msg] gets raw messages that was either just received from the > network interface or the final message that is about to be sent over > network socket. The SIP parsing is done AFTER message has been processed by > this route. So, if SIP parsing fails then it means the message was not > correctly decrypted in event_route [network:msg]. > > Thank you. > > > > On Sat, Sep 20, 2014 at 3:25 PM, Rahul MathuR <rahul.ultimate(a)gmail.com> > wrote: > > > Hello Gents, > > > > Thanks for guiding me in the correct direction. > > > > However, it so came out that when encrypted packets come to kamailio over > > transport 'TCP' then it gets rejected from tcp_read.c even before coming > to > > corex module. > > I guess, maybe it is getting rejected due to header parsing failure. > > > > Any pointers here would be very helpful !! > > > > Thanks one again.. > > > > > > > > On Wed, Sep 17, 2014 at 12:44 PM, Daniel-Constantin Mierla < > > miconda(a)gmail.com> wrote: > > > >> Hello, > >> > >> the corex has a function to tell if the message is received by kamailio > >> or sent out: > >> > >> - http://kamailio.org/docs/modules/devel/modules/corex.html#idp29928 > >> > >> If you interconnect with other servers/gateways when you don't what to > do > >> special encoding, then you need to test src ip or look ar r-uri/dst uri. > >> > >> Cheers, > >> Daniel > >> > >> > >> On 17/09/14 04:24, Rahul MathuR wrote: > >> > >> Hi, > >> > >> Did you get some free cycles to look at it ? > >> > >> On Wed, Sep 17, 2014 at 12:12 AM, Rahul MathuR < > rahul.ultimate(a)gmail.com> > >> wrote: > >> > >>> Thanks for replying ! > >>> > >>> But how to check whether a particular message received by Kamailio was > >>> sent by UAC or SIP Server ? > >>> Also, on the same lines - how to know whether a particular message > about > >>> to be send from Kamailio is bound to UAC or SIP Server ? > >>> > >>> On Tue, Sep 16, 2014 at 10:51 PM, Muhammad Shahzad < > >>> shaheryarkh(a)gmail.com> wrote: > >>> > >>>> Hi, > >>>> > >>>> The network io intercept feature basically allows kamailio script > >>>> writer to do whatever s/he may want to do with raw SIP packets (that > are > >>>> just received by kamailio or about to be sent out by kamailio), e.g. > >>>> encryption, compression or any final touches to sip message before it > is > >>>> processed by kamailio core. That is why it is purposely kept abstract > and > >>>> any particular use or implementation is left to the script writer. > >>>> > >>>> In your case the encryption / decryption code is in C/C++, you can > >>>> try one of the followings, > >>>> > >>>> 1. Writeup a C/C++ program that receives outgoing SIP message as text > >>>> (and some other parameters, e.g. encryption key) in input arguments > and > >>>> returns the encrypted message in event_route [ network:msg ] and vice > versa > >>>> (for incoming messages). You can call this program directly from > >>>> kamailio.cfg script. > >>>> > >>>> 2. Writeup e.g. a PERL wrapper for your encryption / decryption C/C++ > >>>> code and call it using kamailio app_perl module within event_route [ > >>>> network:msg ] as demonstrated in this example, > >>>> > >>>> http://kamailio.org/docs/modules/devel/modules/corex.html#idp125704 > >>>> > >>>> You can also use any other kamailio language bind of you choice as > >>>> well, e.g. Python, LUA, JAVA and so on. > >>>> > >>>> I would recommend the second option, as it has less processing > >>>> overhead for kamailio. > >>>> > >>>> Thank you. > >>>> > >>>> > >>>> > >>>> On Tue, Sep 16, 2014 at 6:09 PM, Rahul MathuR < > >>>> rahul.ultimate(a)gmail.com> wrote: > >>>> > >>>>> Hello, > >>>>> > >>>>> I was going through the new features and stumbled upon this new one > >>>>> - developed by Mohd. Shahzad Shafi. > >>>>> As already mentioned on the wiki about this module, I intend to use > it > >>>>> for my custom security layer between UACs and SIP Proxy (Kamailio) > but the > >>>>> issue is - the custom security layer (encryption/decryption code) is > >>>>> written in C and should precisely be applied for the messages > between UAC > >>>>> and Proxy. > >>>>> > >>>>> Is there a way I can achieve this using Corex module since it does > >>>>> intercept the network I/O messages ? > >>>>> > >>>>> Any help here would be really appreciated. > >>>>> > >>>>> -- > >>>>> Warm Regds. > >>>>> MathuRahul > >>>>> > >>>>> _______________________________________________ > >>>>> sr-dev mailing list > >>>>> sr-dev(a)lists.sip-router.org > >>>>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev > >>>>> > >>>>> > >>>> > >>>> _______________________________________________ > >>>> sr-dev mailing list > >>>> sr-dev(a)lists.sip-router.org > >>>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev > >>>> > >>>> > >>> > >>> > >>> -- > >>> Warm Regds. > >>> MathuRahul > >>> > >> > >> > >> > >> -- > >> Warm Regds. > >> MathuRahul > >> > >> > >> _______________________________________________ > >> sr-dev mailing listsr-dev@lists.sip-router.orghttp:// > lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev > >> > >> > >> -- > >> Daniel-Constantin Mierlahttp://twitter.com/#!/miconda - > http://www.linkedin.com/in/miconda > >> Next Kamailio Advanced Trainings 2014 - http://www.asipto.com > >> Sep 22-25, Berlin, Germany > >> > >> > >> _______________________________________________ > >> sr-dev mailing list > >> sr-dev(a)lists.sip-router.org > >> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev > >> > >> > > > > > > -- > > Warm Regds. > > MathuRahul > > > > _______________________________________________ > > sr-dev mailing list > > sr-dev(a)lists.sip-router.org > > http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev > > > > >

2 1

[tracker] Comment added: zombie dialogs in state 5
by sip-router 20 Sep '14

20 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Daniel-Constantin Mierla (miconda) ---------- That event route is executed inside dlg_manage(), so the sleep() is also run there. I wanted to know what is between dlg_manage() and t_relay(), any other functions? ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1645 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Ovidiu Sas (osas) ---------- Take a look at the "Details" of the report: I have a sleep() in event_route[dialog:end]. ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1644 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Daniel-Constantin Mierla (miconda) ---------- What other functions are you having in config for BYE processing between the dlg_manage() and t_relay()? The dialog ids from context get reset somehow, could be a side effect of another function or event route ... ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1643 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Ovidiu Sas (osas) ---------- <code> $ git diff diff --git a/modules/dialog/dlg_handlers.c b/modules/dialog/dlg_handlers.c index 16c711f..11559ea 100644 --- a/modules/dialog/dlg_handlers.c +++ b/modules/dialog/dlg_handlers.c @@ -713,11 +713,16 @@ void dlg_onreq(struct cell* t, int type, struct tmcb_params *param) sip_msg_t *req = param->req; dlg_cell_t *dlg = NULL; + LM_NOTICE("MICONDA: method_value=[%d]\n", req->first_line.u.request.method_value); + if(req->first_line.u.request.method_value == METHOD_BYE) { _dlg_ctx.t = 1; + LM_NOTICE("MICONDA: method is METHOD_BYE\n"); return; } + LM_NOTICE("MICONDA: method is NOT METHOD_BYE\n"); + if(req->first_line.u.request.method_value != METHOD_INVITE) return; diff --git a/modules/dialog/dlg_hash.c b/modules/dialog/dlg_hash.c index 937f006..27d25e6 100644 --- a/modules/dialog/dlg_hash.c +++ b/modules/dialog/dlg_hash.c @@ -677,6 +677,7 @@ dlg_cell_t* dlg_get_by_iuid(dlg_iuid_t *diuid) { if(diuid==NULL) return NULL; + LM_NOTICE("MICONDA: diuid=[%p] diuid->h_entry=[%d] diuid->h_id=[%d]\n", diuid, diuid->h_entry, diuid->h_id); if(diuid->h_id==0) return NULL; /* dlg ref counter is increased by next line */ diff --git a/modules/dialog/dlg_var.c b/modules/dialog/dlg_var.c index c61cb7f..369e5d6 100644 --- a/modules/dialog/dlg_var.c +++ b/modules/dialog/dlg_var.c @@ -44,9 +44,14 @@ int dlg_cfg_cb(sip_msg_t *msg, unsigned int flags, void *cbp) dlg_cell_t *dlg; if(flags&POST_SCRIPT_CB) { dlg = dlg_get_ctx_dialog(); + LM_NOTICE("MICONDA: dlg=[%p]\n", dlg); if(dlg!=NULL) { + LM_NOTICE("MICONDA: _dlg_ctx.t=[%d] dlg->state=[%d] _dlg_ctx.expect_t=[%d]\n", + _dlg_ctx.t, dlg->state, _dlg_ctx.expect_t); if(_dlg_ctx.t==0 && (dlg->state==DLG_STATE_UNCONFIRMED || _dlg_ctx.expect_t==1)) { + LM_NOTICE("MICONDA: _dlg_ctx.cpid=[%d] _dlg_ctx.cpid=[%d] my_pid=[%d]\n", + _dlg_ctx.cpid, _dlg_ctx.cpid, my_pid()); if(_dlg_ctx.cpid!=0 && _dlg_ctx.cpid==my_pid()) { /* release to destroy dialog if created by this process * and request was not forwarded */ </code> <code> Sep 19 17:56:19 centos k[26594]: INFO: <script>: [2] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as3bbf6251 To:sip:4165552222@192.168.2.62,tag=dcb1d5e18ed8d93eo4 Call-ID:c69950dd-39713a72@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060> Sep 19 17:56:19 centos k[26594]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0] Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is 'dfb.8302' (len=8) Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 3 Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069 Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [3069:8248] Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:1067]: next_state_dlg(): dialog 0x7f97a94ca070 changed from state 4 to state 5, due event 7 (ref 3) Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:855]: dlg_ref(): ref dlg 0x7f97a94ca070 with 1 -> 4 Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_handlers.c:1557]: dlg_run_event_route(): executing event_route 1 on state 5 Sep 19 17:56:19 centos k[26594]: INFO: <script>: [2] entering event_route[dialog:end] Sep 19 17:56:19 centos k[26592]: INFO: <script>: [3] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as3bbf6251 To:sip:4165552222@192.168.2.62,tag=dcb1d5e18ed8d93eo4 Call-ID:c69950dd-39713a72@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060> Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0] Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is 'dfb.8302' (len=8) Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 5 Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069 Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [3069:8248] Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:1067]: next_state_dlg(): dialog 0x7f97a94ca070 changed from state 5 to state 5, due event 7 (ref 5) Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 4 Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=3 , global msg id=2 , T on entrance=0xffffffffffffffff Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=60109, isACK=0 Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failed Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7f97a94ca320, callback type 1, id 0 entered Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7f97a94ca320, callback type 1, id 0 entered Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_handlers.c:716]: dlg_onreq(): MICONDA: method_value=[8] Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_handlers.c:720]: dlg_onreq(): MICONDA: method is METHOD_BYE Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_funcs.c:394]: t_relay_to(): SER: new transaction fwd'ed Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[3069] diuid->h_id=[8248] Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 5 Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069 Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[0x7f97a94ca070] Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_var.c:50]: dlg_cfg_cb(): MICONDA: _dlg_ctx.t=[1] dlg->state=[5] _dlg_ctx.expect_t=[0] Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 4 Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0] Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=2 T start=(nil) Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 60109 label 0 branch 0 Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f97a94ca320)! Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7f97a94ca320, callback type 2, id 0 entered Sep 19 17:56:19 centos k[26590]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a94ccd68] diuid->h_entry=[3069] diuid->h_id=[8248] Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 5 Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069 Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [3069:8248] Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 4 Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T end=0x7f97a94ca320 Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:2210]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0) Sep 19 17:56:19 centos k[26590]: INFO: <script>: [3] entering onreply_route[BYE] for 200 OK Call-ID:c69950dd-39713a72@192.168.2.97 Cseq:102 Sep 19 17:56:19 centos k[26590]: INFO: <script>: [3] exiting onreply_route[BYE] for 200 OK Call-ID:c69950dd-39713a72@192.168.2.97 Cseq:102 Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:1663]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Sep 19 17:56:20 centos k[26594]: INFO: <script>: [2] exiting event_route[dialog:end] Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 3 Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:855]: dlg_ref(): ref dlg 0x7f97a94ca070 with 1 -> 4 Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_handlers.c:1290]: dlg_onroute(): BYE successfully processed Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 2 -> 2 Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 1 Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=2 , global msg id=1 , T on entrance=0xffffffffffffffff Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=60109, isACK=0 Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:470]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=62033d3c Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:726]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f97a94ca320) Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_reply.c:1623]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f97a428a340: SIP/2.0 2..., shmem=0x7f97a94cda68: SIP/2.0 2 Sep 19 17:56:20 centos k[26594]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0] Sep 19 17:56:20 centos k[26594]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[(nil)] Sep 19 17:56:20 centos k[26594]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0] Sep 19 17:56:24 centos k[26597]: DEBUG: dialog [dlg_handlers.c:324]: dlg_iuid_sfree(): freeing dlg iuid [3069:8248] (0x7f97a94ccd68) Sep 19 17:56:26 centos k[26591]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0] Sep 19 17:56:26 centos k[26591]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[(nil)] Sep 19 17:56:26 centos k[26591]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0] </code> ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1642 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Daniel-Constantin Mierla (miconda) ---------- Interesting, the dialog is not found ... can you print also _dlg_ctx.iuid.h_entry and _dlg_ctx.iuid.h_id? These values are set in dlg_onroute() and I couldn't spot any place where they would be reset. ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1641 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Ovidiu Sas (osas) ---------- <code> $ git diff diff --git a/modules/dialog/dlg_handlers.c b/modules/dialog/dlg_handlers.c index 16c711f..11559ea 100644 --- a/modules/dialog/dlg_handlers.c +++ b/modules/dialog/dlg_handlers.c @@ -713,11 +713,16 @@ void dlg_onreq(struct cell* t, int type, struct tmcb_params *param) sip_msg_t *req = param->req; dlg_cell_t *dlg = NULL; + LM_NOTICE("MICONDA: method_value=[%d]\n", req->first_line.u.request.method_value); + if(req->first_line.u.request.method_value == METHOD_BYE) { _dlg_ctx.t = 1; + LM_NOTICE("MICONDA: method is METHOD_BYE\n"); return; } + LM_NOTICE("MICONDA: method is NOT METHOD_BYE\n"); + if(req->first_line.u.request.method_value != METHOD_INVITE) return; diff --git a/modules/dialog/dlg_var.c b/modules/dialog/dlg_var.c index c61cb7f..369e5d6 100644 --- a/modules/dialog/dlg_var.c +++ b/modules/dialog/dlg_var.c @@ -44,9 +44,14 @@ int dlg_cfg_cb(sip_msg_t *msg, unsigned int flags, void *cbp) dlg_cell_t *dlg; if(flags&POST_SCRIPT_CB) { dlg = dlg_get_ctx_dialog(); + LM_NOTICE("MICONDA: dlg=[%p]\n", dlg); if(dlg!=NULL) { + LM_NOTICE("MICONDA: _dlg_ctx.t=[%d] dlg->state=[%d] _dlg_ctx.expect_t=[%d]\n", + _dlg_ctx.t, dlg->state, _dlg_ctx.expect_t); if(_dlg_ctx.t==0 && (dlg->state==DLG_STATE_UNCONFIRMED || _dlg_ctx.expect_t==1)) { + LM_NOTICE("MICONDA: _dlg_ctx.cpid=[%d] _dlg_ctx.cpid=[%d] my_pid=[%d]\n", + _dlg_ctx.cpid, _dlg_ctx.cpid, my_pid()); if(_dlg_ctx.cpid!=0 && _dlg_ctx.cpid==my_pid()) { /* release to destroy dialog if created by this process * and request was not forwarded */ </code> Logs: <code> Sep 19 17:25:16 centos k[11842]: INFO: <script>: [2] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as68b0bfe4 To:sip:4165552222@192.168.2.62,tag=2ace39909579d9f4o4 Call-ID:591136c0-b1777e84@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060> Sep 19 17:25:16 centos k[11842]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is '7f5.018' (len=7) Sep 19 17:25:16 centos k[11842]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fc6e2cb1070 with 1 -> 3 Sep 19 17:25:16 centos k[11842]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2064 found on entry 1527 Sep 19 17:25:16 centos k[11842]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [1527:2064] Sep 19 17:25:16 centos k[11842]: DEBUG: dialog [dlg_hash.c:1066]: next_state_dlg(): dialog 0x7fc6e2cb1070 changed from state 4 to state 5, due event 7 (ref 3) Sep 19 17:25:16 centos k[11842]: DEBUG: dialog [dlg_hash.c:854]: dlg_ref(): ref dlg 0x7fc6e2cb1070 with 1 -> 4 Sep 19 17:25:16 centos k[11842]: DEBUG: dialog [dlg_handlers.c:1557]: dlg_run_event_route(): executing event_route 1 on state 5 Sep 19 17:25:16 centos k[11842]: INFO: <script>: [2] entering event_route[dialog:end] Sep 19 17:25:16 centos k[11843]: INFO: <script>: [3] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as68b0bfe4 To:sip:4165552222@192.168.2.62,tag=2ace39909579d9f4o4 Call-ID:591136c0-b1777e84@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060> Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is '7f5.018' (len=7) Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fc6e2cb1070 with 1 -> 5 Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2064 found on entry 1527 Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [1527:2064] Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_hash.c:1066]: next_state_dlg(): dialog 0x7fc6e2cb1070 changed from state 5 to state 5, due event 7 (ref 5) Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fc6e2cb1070 with 1 -> 4 Sep 19 17:25:16 centos k[11843]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=3 , global msg id=2 , T on entrance=0xffffffffffffffff Sep 19 17:25:16 centos k[11843]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=54471, isACK=0 Sep 19 17:25:16 centos k[11843]: DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failed Sep 19 17:25:16 centos k[11843]: DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Sep 19 17:25:16 centos k[11843]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fc6e2cb1320, callback type 1, id 0 entered Sep 19 17:25:16 centos k[11843]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fc6e2cb1320, callback type 1, id 0 entered Sep 19 17:25:16 centos k[11843]: NOTICE: dialog [dlg_handlers.c:716]: dlg_onreq(): MICONDA: method_value=[8] Sep 19 17:25:16 centos k[11843]: NOTICE: dialog [dlg_handlers.c:720]: dlg_onreq(): MICONDA: method is METHOD_BYE Sep 19 17:25:16 centos k[11843]: DEBUG: tm [t_funcs.c:394]: t_relay_to(): SER: new transaction fwd'ed Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fc6e2cb1070 with 1 -> 5 Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2064 found on entry 1527 Sep 19 17:25:16 centos k[11843]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[0x7fc6e2cb1070] Sep 19 17:25:16 centos k[11843]: NOTICE: dialog [dlg_var.c:50]: dlg_cfg_cb(): MICONDA: _dlg_ctx.t=[1] dlg->state=[5] _dlg_ctx.expect_t=[0] Sep 19 17:25:16 centos k[11843]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fc6e2cb1070 with 1 -> 4 Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=2 T start=(nil) Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 54471 label 0 branch 0 Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fc6e2cb1320)! Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7fc6e2cb1320, callback type 2, id 0 entered Sep 19 17:25:16 centos k[11841]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fc6e2cb1070 with 1 -> 5 Sep 19 17:25:16 centos k[11841]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2064 found on entry 1527 Sep 19 17:25:16 centos k[11841]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [1527:2064] Sep 19 17:25:16 centos k[11841]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fc6e2cb1070 with 1 -> 4 Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T end=0x7fc6e2cb1320 Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_reply.c:2210]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0) Sep 19 17:25:16 centos k[11841]: INFO: <script>: [3] entering onreply_route[BYE] for 200 OK Call-ID:591136c0-b1777e84@192.168.2.97 Cseq:102 Sep 19 17:25:16 centos k[11841]: INFO: <script>: [3] exiting onreply_route[BYE] for 200 OK Call-ID:591136c0-b1777e84@192.168.2.97 Cseq:102 Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 Sep 19 17:25:16 centos k[11841]: DEBUG: tm [t_reply.c:1663]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Sep 19 17:25:17 centos k[11842]: INFO: <script>: [2] exiting event_route[dialog:end] Sep 19 17:25:17 centos k[11842]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fc6e2cb1070 with 1 -> 3 Sep 19 17:25:17 centos k[11842]: DEBUG: dialog [dlg_hash.c:854]: dlg_ref(): ref dlg 0x7fc6e2cb1070 with 1 -> 4 Sep 19 17:25:17 centos k[11842]: DEBUG: dialog [dlg_handlers.c:1290]: dlg_onroute(): BYE successfully processed Sep 19 17:25:17 centos k[11842]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fc6e2cb1070 with 2 -> 2 Sep 19 17:25:17 centos k[11842]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fc6e2cb1070 with 1 -> 1 Sep 19 17:25:17 centos k[11842]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=2 , global msg id=1 , T on entrance=0xffffffffffffffff Sep 19 17:25:17 centos k[11842]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=54471, isACK=0 Sep 19 17:25:17 centos k[11842]: DEBUG: tm [t_lookup.c:470]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=1770ca1a Sep 19 17:25:17 centos k[11842]: DEBUG: tm [t_lookup.c:726]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7fc6e2cb1320) Sep 19 17:25:17 centos k[11842]: DEBUG: tm [t_reply.c:1623]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7fc6dda71340: SIP/2.0 2..., shmem=0x7fc6e2cb4a68: SIP/2.0 2 Sep 19 17:25:17 centos k[11842]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[(nil)] Sep 19 17:25:21 centos k[11847]: DEBUG: dialog [dlg_handlers.c:324]: dlg_iuid_sfree(): freeing dlg iuid [1527:2064] (0x7fc6e2cb3d68) Sep 19 17:25:25 centos k[11845]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[(nil)] </code> ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1640 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Daniel-Constantin Mierla (miconda) ---------- So far so good -- the callback should have not be executed for the delayed BYE processing. Now, can you add some logs in dlg_cfg_cb() from dlg_var()? Print if dlg is found and what is the value for _dlg_ctx.t, _dlg_ctx.expect_t, _dlg_ctx.cpid. I get this postscript executed and the dialog is destroyed. ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1639 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Ovidiu Sas (osas) ---------- The extra logs are showing only for the re-transmitted BYE. The first BYE did not reach the dlg_onreq() function (still stuck in the event_route[dialog:end]). ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1638 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

[tracker] Comment added: zombie dialogs in state 5
by sip-router 19 Sep '14

19 Sep '14

THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY. The following task has a new comment added: FS#424 - zombie dialogs in state 5 User who did this - Ovidiu Sas (osas) ---------- <code> $ kamailio -V version: kamailio 4.2.0-pre0 (x86_64/linux) fef8a4-dirty flags: STATS: Off, USE_TCP, USE_TLS, TLS_HOOKS, USE_RAW_SOCKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, SHM_MMAP, PKG_MALLOC, F_MALLOC, DBG_F_MALLOC, USE_FUTEX, FAST_LOCK-ADAPTIVE_WAIT, USE_DNS_CACHE, USE_DNS_FAILOVER, USE_NAPTR, USE_DST_BLACKLIST, HAVE_RESOLV_RES ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144, MAX_LISTEN 16, MAX_URI_SIZE 1024, BUF_SIZE 65535, DEFAULT PKG_SIZE 4MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: fef8a4 -dirty compiled on 16:31:51 Sep 19 2014 with gcc 4.4.7 $ git diff diff --git a/modules/dialog/dlg_handlers.c b/modules/dialog/dlg_handlers.c index 16c711f..11559ea 100644 --- a/modules/dialog/dlg_handlers.c +++ b/modules/dialog/dlg_handlers.c @@ -713,11 +713,16 @@ void dlg_onreq(struct cell* t, int type, struct tmcb_params *param) sip_msg_t *req = param->req; dlg_cell_t *dlg = NULL; + LM_NOTICE("MICONDA: method_value=[%d]\n", req->first_line.u.request.method_value); + if(req->first_line.u.request.method_value == METHOD_BYE) { _dlg_ctx.t = 1; + LM_NOTICE("MICONDA: method is METHOD_BYE\n"); return; } + LM_NOTICE("MICONDA: method is NOT METHOD_BYE\n"); + if(req->first_line.u.request.method_value != METHOD_INVITE) return; </code> Log output (only tm and dialog): <code> Sep 19 16:33:31 centos k[23777]: INFO: <script>: [2] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as65285b8c To:sip:4165552222@192.168.2.62,tag=8278024a33425aa4o4 Call-ID:ee13dfc2-9eff5b8c@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060> Sep 19 16:33:31 centos k[23777]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is 'af8.d3a' (len=7) Sep 19 16:33:31 centos k[23777]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fe04e73c070 with 1 -> 3 Sep 19 16:33:31 centos k[23777]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2621 found on entry 2298 Sep 19 16:33:31 centos k[23777]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [2298:2621] Sep 19 16:33:31 centos k[23777]: DEBUG: dialog [dlg_hash.c:1066]: next_state_dlg(): dialog 0x7fe04e73c070 changed from state 4 to state 5, due event 7 (ref 3) Sep 19 16:33:31 centos k[23777]: DEBUG: dialog [dlg_hash.c:854]: dlg_ref(): ref dlg 0x7fe04e73c070 with 1 -> 4 Sep 19 16:33:31 centos k[23777]: DEBUG: dialog [dlg_handlers.c:1557]: dlg_run_event_route(): executing event_route 1 on state 5 Sep 19 16:33:31 centos k[23777]: INFO: <script>: [2] entering event_route[dialog:end] Sep 19 16:33:32 centos k[23773]: INFO: <script>: [3] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as65285b8c To:sip:4165552222@192.168.2.62,tag=8278024a33425aa4o4 Call-ID:ee13dfc2-9eff5b8c@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060> Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is 'af8.d3a' (len=7) Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fe04e73c070 with 1 -> 5 Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2621 found on entry 2298 Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [2298:2621] Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_hash.c:1066]: next_state_dlg(): dialog 0x7fe04e73c070 changed from state 5 to state 5, due event 7 (ref 5) Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fe04e73c070 with 1 -> 4 Sep 19 16:33:32 centos k[23773]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=3 , global msg id=2 , T on entrance=0xffffffffffffffff Sep 19 16:33:32 centos k[23773]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=18378, isACK=0 Sep 19 16:33:32 centos k[23773]: DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failed Sep 19 16:33:32 centos k[23773]: DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Sep 19 16:33:32 centos k[23773]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fe04e73c320, callback type 1, id 0 entered Sep 19 16:33:32 centos k[23773]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fe04e73c320, callback type 1, id 0 entered Sep 19 16:33:32 centos k[23773]: NOTICE: dialog [dlg_handlers.c:716]: dlg_onreq(): MICONDA: method_value=[8] Sep 19 16:33:32 centos k[23773]: NOTICE: dialog [dlg_handlers.c:720]: dlg_onreq(): MICONDA: method is METHOD_BYE Sep 19 16:33:32 centos k[23773]: DEBUG: tm [t_funcs.c:394]: t_relay_to(): SER: new transaction fwd'ed Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fe04e73c070 with 1 -> 5 Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2621 found on entry 2298 Sep 19 16:33:32 centos k[23773]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fe04e73c070 with 1 -> 4 Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=2 T start=(nil) Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 18378 label 0 branch 0 Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe04e73c320)! Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7fe04e73c320, callback type 2, id 0 entered Sep 19 16:33:32 centos k[23776]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fe04e73c070 with 1 -> 5 Sep 19 16:33:32 centos k[23776]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=2621 found on entry 2298 Sep 19 16:33:32 centos k[23776]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [2298:2621] Sep 19 16:33:32 centos k[23776]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fe04e73c070 with 1 -> 4 Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T end=0x7fe04e73c320 Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_reply.c:2210]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0) Sep 19 16:33:32 centos k[23776]: INFO: <script>: [3] entering onreply_route[BYE] for 200 OK Call-ID:ee13dfc2-9eff5b8c@192.168.2.97 Cseq:102 Sep 19 16:33:32 centos k[23776]: INFO: <script>: [3] exiting onreply_route[BYE] for 200 OK Call-ID:ee13dfc2-9eff5b8c@192.168.2.97 Cseq:102 Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 Sep 19 16:33:32 centos k[23776]: DEBUG: tm [t_reply.c:1663]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Sep 19 16:33:33 centos k[23777]: INFO: <script>: [2] exiting event_route[dialog:end] Sep 19 16:33:33 centos k[23777]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fe04e73c070 with 1 -> 3 Sep 19 16:33:33 centos k[23777]: DEBUG: dialog [dlg_hash.c:854]: dlg_ref(): ref dlg 0x7fe04e73c070 with 1 -> 4 Sep 19 16:33:33 centos k[23777]: DEBUG: dialog [dlg_handlers.c:1290]: dlg_onroute(): BYE successfully processed Sep 19 16:33:33 centos k[23777]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fe04e73c070 with 2 -> 2 Sep 19 16:33:33 centos k[23777]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7fe04e73c070 with 1 -> 1 Sep 19 16:33:33 centos k[23777]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=2 , global msg id=1 , T on entrance=0xffffffffffffffff Sep 19 16:33:33 centos k[23777]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=18378, isACK=0 Sep 19 16:33:33 centos k[23777]: DEBUG: tm [t_lookup.c:470]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=43a8d12b Sep 19 16:33:33 centos k[23777]: DEBUG: tm [t_lookup.c:726]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7fe04e73c320) Sep 19 16:33:33 centos k[23777]: DEBUG: tm [t_reply.c:1623]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7fe0494fc340: SIP/2.0 2..., shmem=0x7fe04e73fa68: SIP/2.0 2 Sep 19 16:33:37 centos k[23779]: DEBUG: dialog [dlg_handlers.c:324]: dlg_iuid_sfree(): freeing dlg iuid [2298:2621] (0x7fe04e73ed68) </code> The zombie dialog: <code> $ kamcmd dlg.list { h_entry: 2298 h_id: 2621 call-id: ee13dfc2-9eff5b8c(a)192.168.2.97 from_uri: sip:4165552222@192.168.2.62 to_uri: sip:1234@192.168.2.62 state: 5 start_ts: 1411158800 init_ts: 1411158797 timeout: 0 lifetime: 3600 dflags: 519 sflags: 0 iflags: 1 caller: { tag: 8278024a33425aa4o4 contact: sip:4165552222@192.168.2.97:5064 cseq: 101 route_set: socket: udp:192.168.2.62:5060 } callee: { tag: as65285b8c contact: sip:240@192.168.2.2:5060 cseq: 0 route_set: socket: udp:192.168.2.62:5060 } profiles: { } variables: { } } </code> ---------- More information can be found at the following URL: http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1637 You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.

1 0

← Newer
1
...
7
8
9
10
11
12
13
...
38
Older →

Jump to page:

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

sr-dev September 2014