Email with attatchement. 2016-06-21 12:22 GMT+01:00 José Seabra <joseseab...@gmail.com>:
> Hello, > Thanks for the hint to use cherry-pick, > > After applying these two patches the issue seems to be fixed. > Please find out the attachment with kamailio logs. > > Thank you for your help. > > Regards > José > > 2016-06-21 10:41 GMT+01:00 Daniel-Constantin Mierla <mico...@gmail.com>: > >> Hello, >> >> you should cherry-pick them with git into the branch 4.4. >> >> Cheers, >> Daniel >> >> On 21/06/16 11:04, José Seabra wrote: >> >> Hi Daniel, >> I have applied your last two patchs on tm module but i can't compile the >> module because kemi framwork. >> >> I'm using kamailio version 4.4. >> >> Compile Errors: >> >> t_fwd.c:61:24: error: ../../kemi.h: No such file or directory >> t_fwd.c: In function ‘prepare_new_uac’: >> t_fwd.c:157: error: ‘sr_kemi_eng_t’ undeclared (first use in this >> function) >> t_fwd.c:157: error: (Each undeclared identifier is reported only once >> t_fwd.c:157: error: for each function it appears in.) >> t_fwd.c:157: error: ‘keng’ undeclared (first use in this function) >> t_fwd.c:346: warning: implicit declaration of function ‘sr_kemi_eng_get’ >> t_fwd.c:348: warning: implicit declaration of function >> ‘sr_kemi_act_ctx_get’ >> t_fwd.c:348: warning: assignment makes pointer from integer without a cast >> t_fwd.c:350: warning: implicit declaration of function >> ‘sr_kemi_act_ctx_set’ >> t_fwd.c:352: warning: implicit declaration of function >> ‘sr_kemi_cbname_lookup_idx’ >> t_fwd.c: In function ‘add_blind_uac’: >> t_fwd.c:715: error: ‘TM_UAC_FLAG_BLIND’ undeclared (first use in this >> function) >> make: *** [t_fwd.o] Error 1 >> >> Regards >> José >> >> >> 2016-06-21 9:38 GMT+01:00 José Seabra <joseseab...@gmail.com>: >> >>> Hello Daniel, >>> >>> I confirm that the phone receives 200 OK to the CANCEL and 487 to the >>> INVITE. >>> >>> I will backport the tm module to the last two patchs, then, after make >>> all tests I will report back to you. >>> >>> Regards >>> José >>> >>> 2016-06-21 5:33 GMT+01:00 Daniel-Constantin Mierla < <mico...@gmail.com> >>> mico...@gmail.com>: >>> >>>> Can you try with master branch or backport the last two patches from tm >>>> module? I pushed two commits that should catch and handle better this case. >>>> >>>> Cheers, >>>> Daniel >>>> >>>> On 20/06/16 18:14, Daniel-Constantin Mierla wrote: >>>> >>>> Hello, >>>> >>>> it seems it tries to generate an outgoing cancel for the suspended >>>> branch. I will check the code, likely there has to be added condition for >>>> this cases. >>>> >>>> Is the 487 reply for invite sent back? Also, the 200ok for cancel? >>>> >>>> Cheers, >>>> Daniel >>>> >>>> On 20/06/16 16:38, José Seabra wrote: >>>> >>>> Hello, >>>> >>>> I'm attaching more logs to this email regarding to the issue on SIP >>>> CANCEL to an INVITE that is suspended. >>>> >>>> If do you think that i should open an issue on git regarding to this >>>> let me know. >>>> >>>> Thank you for your help. >>>> >>>> Best Regards >>>> José >>>> >>>> 2016-06-15 14:42 GMT+01:00 José Seabra < <joseseab...@gmail.com> >>>> joseseab...@gmail.com>: >>>> >>>>> Hi Daniel, >>>>> >>>>> But when Kamailio receives a CANCEL prints the following error >>>>> messages: >>>>> >>>>> 2016-06-15 14:39:10.354 ERROR: tm [t_msgbuilder.c:287]: >>>>> build_local_reparse(): ERROR: build_local_reparse: INVITE is missing >>>>> 2016-06-15 14:39:10.354 ERROR: tm [t_msgbuilder.c:494]: >>>>> build_local_reparse(): ERROR: build_local_reparse: cannot build CANCEL >>>>> request >>>>> 2016-06-15 14:39:10.354 ERROR: tm [t_cancel.c:310]: cancel_branch(): >>>>> ERROR: attempt to build a CANCEL failed >>>>> 2016-06-15 14:39:10.354 ERROR: tm [t_fwd.c:1389]: e2e_cancel(): >>>>> ERROR: cancel error >>>>> >>>>> I'm handling the CANCEL in the script by the following way: >>>>> >>>>> >>>>> if (is_method("CANCEL")) { >>>>> if (t_check_trans()) { >>>>> route(RELAY); >>>>> } else { >>>>> sl_send_reply("481", "Call leg/transaction >>>>> does not exist"); >>>>> } >>>>> exit(); >>>>> ... >>>>> ... >>>>> ... >>>>> >>>>> Thank you for your support. >>>>> >>>>> Regards >>>>> José >>>>> >>>>> >>>>> 2016-06-15 12:15 GMT+01:00 Daniel-Constantin Mierla < >>>>> <mico...@gmail.com>mico...@gmail.com>: >>>>> >>>>>> Hello, >>>>>> >>>>>> On 14/06/16 16:33, José Seabra wrote: >>>>>> >>>>>> Hi Olle and Daniel, >>>>>> Thank you for your replies, After receive your msg I looked again to >>>>>> my script and i found the problem. >>>>>> >>>>>> I didn't configure the correct failure_route block and the failure >>>>>> route configured didn't print any msg on the logs, so I thought that >>>>>> it wasn't entering on failure route. >>>>>> >>>>>> Sorry for my mistake. >>>>>> >>>>>> Anyway, How should i handle the CANCEL sip msg to an INVITE that is >>>>>> suspended? (still related with this implementation) >>>>>> >>>>>> Just handling it as done in the default configuration file is ok -- >>>>>> the suspended transaction will be canceled. >>>>>> >>>>>> Cheers, >>>>>> Daniel >>>>>> >>>>> >>>> -- >>>> Daniel-Constantin Mierlahttp://www.asipto.com - >>>> http://www.kamailio.orghttp://twitter.com/#!/miconda - >>>> http://www.linkedin.com/in/miconda >>>> >>>> >>> >>> >>> -- >>> Cumprimentos >>> José Seabra >>> >> >> >> >> -- >> Cumprimentos >> José Seabra >> >> >> -- >> Daniel-Constantin Mierlahttp://www.asipto.com - >> http://www.kamailio.orghttp://twitter.com/#!/miconda - >> http://www.linkedin.com/in/miconda >> >> > > > -- > Cumprimentos > José Seabra > -- Cumprimentos José Seabra
2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [receive.c:221]: receive_msg(): preparing to run routing scripts... 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <750311> <CANCEL> 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=9PV_tw 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 49 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tmx [tmx_pretran.c:219]: tmx_check_pretran(): no pre-transaction management for ACK or CANCEL 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} WARNING: <script>: ABC SOURCE - 10.112.185.128:5060 ID=VqT_vW5XY2B6A0xuYa7mmQ 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} INFO: <script>: New request - M=CANCEL R=sip:sec.abcsoft.503@example.local F=sip:502@example.local T=sip:sec.abcsoft.503@example.local IP=udp:10.112.185.128:5060 ID=VqT_vW5XY2B6A0xuYa7mmQ 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: siputils [checks.c:97]: has_totag(): no totag 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:701]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: searching on hash entry 20684 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=iz-kuh802broqT9YKBLjHg 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:798]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: canceled transaction found (0x7f744dfd09a8)! 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:801]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT completed 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [tm.c:992]: w_t_lookup_cancel(): lookup_original: t_lookupOriginalT returned: 0x7f744dfd09a8 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=9 , global msg id=8 , T on entrance=0xffffffffffffffff 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=20684, isACK=0 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f744dfed4b8, callback type 1, id 0 entered 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 29f9b25910de6b835aee5e8cb41de048 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:701]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: searching on hash entry 20684 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=iz-kuh802broqT9YKBLjHg 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:798]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: canceled transaction found (0x7f744dfd09a8)! 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_lookup.c:801]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT completed 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_reply.c:1592]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_reply.c:648]: _reply_light(): DEBUG: reply sent out. buf=0x7f7451b93610: SIP/2.0 487 Request ..., shmem=0x7f744dfd9b18: SIP/2.0 487 Request 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_reply.c:658]: _reply_light(): DEBUG: _reply_light: finished 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_fwd.c:1279]: e2e_cancel(): DEBUG: e2e_cancel: e2e cancel -- no active branches 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_reply.c:1592]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_reply.c:648]: _reply_light(): DEBUG: reply sent out. buf=0x7f7451b93610: SIP/2.0 200 ok -- no..., shmem=0x7f744dfe19c0: SIP/2.0 200 ok -- no 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_reply.c:658]: _reply_light(): DEBUG: _reply_light: finished 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tm [t_funcs.c:368]: t_relay_to(): SER: new transaction fwd'ed 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) 2016-06-21 12:18:57.008 {1 CANCEL VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: <core> [receive.c:322]: receive_msg(): cleaning up 2016-06-21 12:18:57.013 {1 ACK VqT_vW5XY2B6A0xuYa7mmQ} DEBUG: tmx [tmx_pretran.c:219]: tmx_check_pretran(): no pre-transaction management for ACK or CANCEL
_______________________________________________ SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list sr-users@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users