[SR-Users] ACK no transaction found if re-invite

Daniel-Constantin Mierla miconda at gmail.com
Wed Aug 12 11:26:07 CEST 2020


Hello,

can you send the SIP traffic of such call in a pcap file? It makes it
easier to investigate with sngrep/wireshark instead of trying to read
through text output of the SIP traffic that you pasted in the first email.

Cheers,
Daniel

On 12.08.20 11:05, Techinnovation wrote:
> Re: [SR-Users] ACK no transaction found if re-invite Hello Daniel !
>
> I add record_route(). Still have same result . 
>
> now my config is : 
>
> route[WITHINDLG] {
>        if (has_totag()) {
>                 record_route();
>                # sequential request withing a dialog should
>                # take the path determined by record-routing
>                if (loose_route()) {
>                        if (is_method("BYE")) {
>                                setflag(FLT_ACC); # do accounting ...
>                                setflag(FLT_ACCFAILED); # ... even if
> the transaction fails
>                        }
>                        route(RELAY);
>                } 
>                else {
>                        if (is_method("SUBSCRIBE") && uri == myself) {
>                                # in-dialog subscribe requests
>                                route(PRESENCE);
>                                exit;
>                        }
>                        if ( is_method("ACK") ) {
>                                if ( t_check_trans() ) {
>                                        # non loose-route, but stateful
> ACK;
>                                        # must be ACK after a 487 or
> e.g. 404 from upstream server
>                                        t_relay();
>                                        exit;
>                                } else {
>                                        # ACK without matching
> transaction ... ignore and discard.
>                                        exit;
>                                }
>                        }
>                                        t_relay();
>                }
>                exit;
>        }
> }
>
>
>
> kamailio log
>
> [4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060] - cseq:
> [18259129 ACK]
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: <core>
> [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: <core>
> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/receive.c:296]: receive_msg(): preparing to run routing scripts...
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sl
> [sl_funcs.c:397]: sl_filter_ACK(): too late to be a local ACK!
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} maxfwd
> [mf_funcs.c:74]: is_maxfwd_present(): value = 70
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:247]: check_ruri_sip_version(): check_ruri_sip_version entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:270]: check_ruri_sip_version(): check_ruri_sip_version passed
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:277]: check_ruri_scheme(): check_ruri_scheme entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:297]: check_ruri_scheme(): check_ruri_scheme passed
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:305]: check_required_headers(): check_required_headers entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:313]: check_required_headers(): check_required_headers passed
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:479]: check_cseq_method(): check_cseq_method entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:512]: check_cseq_method(): check_cseq_method passed
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:521]: check_cseq_value(): check_cseq_value entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:549]: check_cseq_value(): check_cseq_value passed
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:558]: check_cl(): check_cl entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:583]: check_cl(): check_cl passed
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:595]: check_expires_value(): check_expires_value entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:632]: check_expires_value(): no expires header found
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:732]: check_parse_uris(): check_parse_uris entered
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:736]: check_parse_uris(): parsing ruri
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:750]: check_parse_uris(): looking up From header
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:764]: check_parse_uris(): parsing From header
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param:
> tag=00-07198-01aa7c42-632e1ccc6
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header
> reached, state=29
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:790]: check_parse_uris(): parsing From URI
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:808]: check_parse_uris(): looking up To header
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:830]: check_parse_uris(): check_parse_uris(): parsing To URI
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity.c:875]: check_parse_uris(): check_parse_uris passed
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} sanity
> [sanity_mod.c:254]: w_sanity_check(): sanity checks result: 1
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} siputils
> [checks.c:123]: has_totag(): totag found
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} rr
> [record.c:475]: record_route(): inserted record route (r2: 1 - plen: 0)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} rr
> [loose.c:114]: find_first_route(): No Route headers found
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} rr
> [loose.c:961]: loose_route(): There is no Route HF
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} tm
> [t_lookup.c:1043]: t_check_msg(): msg (0x7fe00b433288) id=20/28987
> global id=19/28987 T start=(nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} tm
> [t_lookup.c:500]: t_lookup_request(): start searching: hash=30828, isACK=1
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} tm
> [t_lookup.c:458]: matching_3261(): RFC3261 transaction matching failed
> - via branch [z9hG4bK-4F08643B-1884F5D]
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} tm
> [t_lookup.c:682]: t_lookup_request(): no transaction found
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} tm
> [t_lookup.c:1113]: t_check_msg(): msg (0x7fe00b433288) id=20/28987
> global id=20/28987 T end=(nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/receive.c:354]: receive_msg(): request-route executed in: 158 usec
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/xavp.c:507]: xavp_destroy_list(): destroying xavp list (nil)
> Aug 12 11:57:48 c4-proxy-1 kamailio: 21(28987) DEBUG: {1 18259129 ACK
> 4fdf3fe8321fc64667329489142bc36f at 48.146.161.152:5060} <core>
> [core/receive.c:458]: receive_msg(): cleaning up
>
> [...]
> /-- 
> Best regards,
>  Techinnovation                          
>  /mailto:techinnovation at inbox.lv 

-- 
Daniel-Constantin Mierla -- www.asipto.com
www.twitter.com/miconda -- www.linkedin.com/in/miconda
Funding: https://www.paypal.me/dcmierla

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.kamailio.org/pipermail/sr-users/attachments/20200812/921099a6/attachment.htm>


More information about the sr-users mailing list