My kamailio version is:

version: kamailio 4.4.6 (x86_64/linux) becbde flags: STATS: Off, USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, SHM_MMAP, PKG_MALLOC, Q_MALLOC, F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, 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 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: becbde compiled on 08:18:45 Jul 3 2017 with gcc 5.4.0

I've pasted the logs below.

( ... )

15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request: Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <INVITE> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: < sip:+3412345678910@146.185.142.19:9773> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63> ; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=466 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=mf_process_maxfwd_header Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=26 n=sanity_check Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=9 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [35]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ sip:+3412345678910@146.185.142.19#015#012] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21647> <INVITE> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=479 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=469 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=764 a=55 n=force_rport Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=765 a=25 n=nat_uac_test Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (37.29.170.95, 37.29.170.95, 0) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=24 n=has_totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:97]: has_totag(): no totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=489 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=485 a=24 n=t_precheck_trans Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=489 a=24 n=t_check_trans Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global id=9 T start=(nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7756, isACK=0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global id=10 T end=(nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=492 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=752 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=739 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=758 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=758 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=496 a=25 n=remove_hf Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=501 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=497 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=498 a=24 n=record_route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=506 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=501 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=502 a=39 n=setflag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=506 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=828 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=826 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=511 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=697 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=694 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=695 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=514 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=639 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=637 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=637 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=523 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=523 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PSTN] c=[/etc/kamailio/kamailio.cfg] l=865 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=526 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=667 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=663 a=25 n=alias_db_lookup Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:115]: db_new_result(): allocate 56 bytes for result set at 0x7fa015dc2c48 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 2 columns returned from the query Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:152]: db_allocate_columns(): allocate 16 bytes for result names at 0x7fa015dc2d50 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:163]: db_allocate_columns(): allocate 8 bytes for result types at 0x7fa015dc2dc0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7fa015dc2e28 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7fa015dc2e28)[0]=[username] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7fa015dc2e98 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7fa015dc2e98)[1]=[domain] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:168]: db_mysql_convert_rows(): no rows returned from the query Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: alias_db [alookup.c:117]: alias_db_lookup(): no alias found for R-URI Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 2 columns Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7fa015dc2e28 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7fa015dc2e98 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:92]: db_free_columns(): freeing result names at 0x7fa015dc2d50 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:97]: db_free_columns(): freeing result types at 0x7fa015dc2dc0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:134]: db_free_result(): freeing result set at 0x7fa015dc2c48 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=667 a=63 n=assign Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=668 a=25 n=lookup Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: registrar [lookup.c:196]: lookup_helper(): contact for [+3412345678910] found by address Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=688 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=685 a=39 n=setflag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=688 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_is_set Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_on_branch Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=539 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=538 a=25 n=t_is_set Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=538 a=25 n=t_on_reply Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=542 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=541 a=25 n=t_is_set Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=541 a=25 n=t_on_failure Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=24 n=t_relay Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=10 , global msg id=10 , T on entrance=(nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7756, isACK=0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 1, id 0 entered Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: f3913fdf4b6cd76583034cec844eb6cd Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:315]: t_relay_to(): SER: new INVITE Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2195]: tcpconn_send_put(): send from reader (3099 (11)), reusing fd Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf44ae0 n=430 fd=9 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012SIP/2.0 100 trying -- your call is important to us#015#012Via: SIP/2.0/TCP 37.29 .170.95:33335;rport=33335;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias;received=37.29.170.95#015#012From: sip:+34639446862@146.185.142.19 ;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015# 012To: sip:+3412345678910@146.185.142.19#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 INVITE#015#012Server: kamailio (4.3.4 (x86_64/linux))#015#012Content-Length: 0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_reply.c:647]: _reply_light(): DEBUG: reply sent out. buf=0x7fa015dc30b0: SIP/2.0 100 trying -..., shmem=0x7fa00bf4c3f8: SIP/2.0 100 trying - Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_reply.c:657]: _reply_light(): DEBUG: _reply_light: finished Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=918 a=25 n=xdbg Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <script>: new branch [0] to sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=919 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=789 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=24 n=has_totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:97]: has_totag(): no totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:2843]: create_via_hf(): id added: <;i=5>, rcv proto=2 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2211]: tcpconn_send_put(): tcp connection found (0x7fa00bf40488), acquiring fd Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3475]: handle_ser_child(): read response= 7fa00bf40488, 2, fd -1 from 11 (3099) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2221]: tcpconn_send_put(): c=0x7fa00bf40488, n=16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2251]: tcpconn_send_put(): after receive_fd: c= 0x7fa00bf40488 n=8 fd=11 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf40488 n=1340 fd=11 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012INVITE sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012Rec ord-Route: sip:146.185.142.19:9773;transport=tcp;lr#015#012Via: SIP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bKc4e1.f3913fdf4b6cd76583034cec844eb6cd.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:3333 5;received=37.29.170.95;rport=33335;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19 ;tag=d48d0318-ea78-44ed-90b7-0634b 2ee4f40#015#012To: sip:+3412345678910@146.185.142.19#015#012Contact: sip:+34639446862@37.29.170.95:33335;transport=TCP;ob#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 I NVITE#015#012Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS#015#012Supported: replaces, 100rel, timer, norefersub#015#012Session-Expires: 1800#015# 012Min-SE: 90#015#012Content-Type: application/sdp#015#012Content-Length: 479#015#012#015#012v=0#015#012o=- 3727693372 3727693372 IN IP4 100.108.48.229#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 4004 RTP/AVP 98 97 99 104 3 0 8 9 96#015#012c=IN IP4 100.108.48.229#015#012b=TIAS:64000#015#012a=rtcp:4005 IN IP4 100.108.48.229#015#012a=sendrecv#015#012a=rtpmap: 98 speex/16000#015#012a=rtpmap:97 speex/8000#015#012a=rtpmap:99 speex/32000#015#012a=rtpmap:104 iLBC/8000#015#012a=fmtp:104 mode=30#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:0 PCMU/8000#015#012a=r tpmap:8 PCMA/8000#015#012a=rtpmap:9 G722/8000#015#012a=rtpmap:96 telephone-event/8000#015#012a=fmtp:96 0-16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:362]: t_relay_to(): SER: new transaction fwd'ed Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=2 n=exit Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [io_wait.h:376]: io_watch_add(): DBG: io_watch_add(0xa81660, 9, 2, 0x7fa00bf44ae0), fd_no=1 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [io_wait.h:598]: io_watch_del(): DBG: io_watch_del (0xa28d40, 28, -1, 0x0) fd_no=20 called Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:4131]: handle_tcpconn_ev(): sending to child, events 1 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3810]: send2child(): selected tcp worker 2 12(3100) for activity on [tcp:146.185.142.19:9773], 0x7fa00bf40488 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1539]: handle_io(): received n=8 con=0x7fa00bf40488, fd=9 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=0

( ... )

Thanks!

2018-02-15 15:02 GMT+01:00 Daniel-Constantin Mierla miconda@gmail.com:

Hello,

first, what is the version of kamailio?

Then, can you load debugger module and set cfgtrace=1, then send here all log messages for a case exposing this issue? It should show what actions are executed from config.

Cheers, Daniel

On 15.02.18 12:08, Pau Giralt wrote:

Hi!

I'm working with kamailio and rtpproxy. When I configure the clients to send sip messages through TCP adding the ";transport=tcp" in the sip message the rtpproxy is not working anymore.

The code is never passing the point pasted below, inside route[NATMANAGE] in kamailio.cfg

if (!(isflagset(FLT_NATS) || isbflagset(FLB_NATB))) return;

Therefore, rtpproxy_manage("co"); is never reached.

Could it be a parsing problem? ( check_route_param("nat=yes") )

The config file is exactly the same as the one provided in the kamailio repository (the default one)

Thanks!

---

Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio Advanced Training - March 5-7, 2018, Berlin - www.asipto.com Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com

These are the complete logs for the previous call:

Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [io_wait.h:598]: io_watch_del(): DBG: io_watch_del (0xa28d40, 29, -1, 0x0) fd_no=21 called Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:4131]: handle_tcpconn_ev(): sending to child, events 1 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3810]: send2child(): selected tcp worker 1 11(3099) for activity on [tcp:146.185.142.19:9773], 0x7fa00bf44ae0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1539]: handle_io(): received n=8 con=0x7fa00bf44ae0, fd=9 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=479 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request: Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <INVITE> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: < sip:+3412345678910@146.185.142.19:9773> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63> ; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=466 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=mf_process_maxfwd_header Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=26 n=sanity_check Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=9 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [35]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ sip:+3412345678910@146.185.142.19#015#012] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21647> <INVITE> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=479 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=469 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=764 a=55 n=force_rport Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=765 a=25 n=nat_uac_test Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (37.29.170.95, 37.29.170.95, 0) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=24 n=has_totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:97]: has_totag(): no totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=489 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=485 a=24 n=t_precheck_trans Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=489 a=24 n=t_check_trans Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global id=9 T start=(nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7756, isACK=0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global id=10 T end=(nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=492 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=752 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=739 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=758 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=758 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=496 a=25 n=remove_hf Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=501 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=497 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=498 a=24 n=record_route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=506 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=501 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=502 a=39 n=setflag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=506 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=828 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=826 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=511 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=697 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=694 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=695 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=514 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=639 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=637 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=637 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=523 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=523 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[PSTN] c=[/etc/kamailio/kamailio.cfg] l=865 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=526 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=667 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=663 a=25 n=alias_db_lookup Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:115]: db_new_result(): allocate 56 bytes for result set at 0x7fa015dc2c48 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 2 columns returned from the query Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:152]: db_allocate_columns(): allocate 16 bytes for result names at 0x7fa015dc2d50 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:163]: db_allocate_columns(): allocate 8 bytes for result types at 0x7fa015dc2dc0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7fa015dc2e28 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7fa015dc2e28)[0]=[username] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7fa015dc2e98 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7fa015dc2e98)[1]=[domain] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: db_mysql [km_res.c:168]: db_mysql_convert_rows(): no rows returned from the query Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: alias_db [alookup.c:117]: alias_db_lookup(): no alias found for R-URI Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 2 columns Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7fa015dc2e28 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7fa015dc2e98 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:92]: db_free_columns(): freeing result names at 0x7fa015dc2d50 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:97]: db_free_columns(): freeing result types at 0x7fa015dc2dc0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [db_res.c:134]: db_free_result(): freeing result set at 0x7fa015dc2c48 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=667 a=63 n=assign Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=668 a=25 n=lookup Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: registrar [lookup.c:196]: lookup_helper(): contact for [+3412345678910] found by address Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=688 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=685 a=39 n=setflag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=688 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_is_set Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_on_branch Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=539 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=538 a=25 n=t_is_set Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=538 a=25 n=t_on_reply Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=25 n=is_method Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=542 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=541 a=25 n=t_is_set Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=541 a=25 n=t_on_failure Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=24 n=t_relay Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=10 , global msg id=10 , T on entrance=(nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7756, isACK=0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 1, id 0 entered Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: f3913fdf4b6cd76583034cec844eb6cd Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:315]: t_relay_to(): SER: new INVITE Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2195]: tcpconn_send_put(): send from reader (3099 (11)), reusing fd Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf44ae0 n=430 fd=9 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012SIP/2.0 100 trying -- your call is important to us#015#012Via: SIP/2.0/TCP 37.29 .170.95:33335;rport=33335;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias;received=37.29.170.95#015#012From: sip:+34639446862@146.185.142.19 ;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015# 012To: sip:+3412345678910@146.185.142.19#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 INVITE#015#012Server: kamailio (4.3.4 (x86_64/linux))#015#012Content-Length: 0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_reply.c:647]: _reply_light(): DEBUG: reply sent out. buf=0x7fa015dc30b0: SIP/2.0 100 trying -..., shmem=0x7fa00bf4c3f8: SIP/2.0 100 trying - Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_reply.c:657]: _reply_light(): DEBUG: _reply_light: finished Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=918 a=25 n=xdbg Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <script>: new branch [0] to sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=919 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=789 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=24 n=has_totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:97]: has_totag(): no totag Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:2843]: create_via_hf(): id added: <;i=5>, rcv proto=2 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2211]: tcpconn_send_put(): tcp connection found (0x7fa00bf40488), acquiring fd Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3475]: handle_ser_child(): read response= 7fa00bf40488, 2, fd -1 from 11 (3099) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2221]: tcpconn_send_put(): c=0x7fa00bf40488, n=16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2251]: tcpconn_send_put(): after receive_fd: c= 0x7fa00bf40488 n=8 fd=11 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf40488 n=1340 fd=11 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012INVITE sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012Rec ord-Route: sip:146.185.142.19:9773;transport=tcp;lr#015#012Via: SIP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bKc4e1.f3913fdf4b6cd76583034cec844eb6cd.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:3333 5;received=37.29.170.95;rport=33335;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19 ;tag=d48d0318-ea78-44ed-90b7-0634b 2ee4f40#015#012To: sip:+3412345678910@146.185.142.19#015#012Contact: sip:+34639446862@37.29.170.95:33335;transport=TCP;ob#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 I NVITE#015#012Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS#015#012Supported: replaces, 100rel, timer, norefersub#015#012Session-Expires: 1800#015# 012Min-SE: 90#015#012Content-Type: application/sdp#015#012Content-Length: 479#015#012#015#012v=0#015#012o=- 3727693372 3727693372 IN IP4 100.108.48.229#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 4004 RTP/AVP 98 97 99 104 3 0 8 9 96#015#012c=IN IP4 100.108.48.229#015#012b=TIAS:64000#015#012a=rtcp:4005 IN IP4 100.108.48.229#015#012a=sendrecv#015#012a=rtpmap: 98 speex/16000#015#012a=rtpmap:97 speex/8000#015#012a=rtpmap:99 speex/32000#015#012a=rtpmap:104 iLBC/8000#015#012a=fmtp:104 mode=30#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:0 PCMU/8000#015#012a=r tpmap:8 PCMA/8000#015#012a=rtpmap:9 G722/8000#015#012a=rtpmap:96 telephone-event/8000#015#012a=fmtp:96 0-16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:362]: t_relay_to(): SER: new transaction fwd'ed Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=2 n=exit Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [io_wait.h:376]: io_watch_add(): DBG: io_watch_add(0xa81660, 9, 2, 0x7fa00bf44ae0), fd_no=1 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [io_wait.h:598]: io_watch_del(): DBG: io_watch_del (0xa28d40, 28, -1, 0x0) fd_no=20 called Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:4131]: handle_tcpconn_ev(): sending to child, events 1 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3810]: send2child(): selected tcp worker 2 12(3100) for activity on [tcp:146.185.142.19:9773], 0x7fa00bf40488 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1539]: handle_io(): received n=8 con=0x7fa00bf40488, fd=9 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <100> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <Trying> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <146.185.142.19>; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKc4e1.f3913fdf4b6cd76583034cec844eb6cd.0

; state=6

Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 236, <i> = <5>; state=16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=7 global id=6 T start=(nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <33335>; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <37.29.170.95>; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63> ; state=6 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [37]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [< sip:+3412345678910@146.185.142.19>#015#012] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21647> <INVITE> Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 7756 label 0 branch 0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fa00bf492c0)! Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 2, id 0 entered Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7fa00bf492c0) event type 2, reply code 100 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=7 global id=7 T end=0x7fa00bf492c0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=924 a=25 n=xdbg Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <script>: incoming reply Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=927 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=926 a=5 n=route Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=100 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=-1 icode=0 Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:22:54 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [io_wait.h:376]: io_watch_add(): DBG: io_watch_add(0xa81660, 9, 2, 0x7fa00bf40488), fd_no=1 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=275 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <200> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <OK> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <146.185.142.19>; state=6 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKc4e1.f3913fdf4b6cd76583034cec844eb6cd.0

; state=6

Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 236, <i> = <5>; state=16 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=8 global id=7 T start=0xffffffffffffffff Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <33335>; state=6 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <37.29.170.95>; state=6 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63> ; state=6 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [78]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [< sip:+3412345678910@146.185.142.19>] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21647> <INVITE> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 7756 label 0 branch 0 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fa00bf492c0)! Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 2, id 0 entered Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7fa00bf492c0) event type 2, reply code 200 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=8 global id=8 T end=0x7fa00bf492c0 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=924 a=25 n=xdbg Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <script>: incoming reply Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=927 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=926 a=5 n=route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=200 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=275 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [msg_translator.c:2229]: generate_res_buf_from_sip_res(): old size: 1152, new size: 1032 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [msg_translator.c:2246]: generate_res_buf_from_sip_res(): copied size: orig:136, new: 16, rest: 1016 msg=#012SIP/2.0 200 OK#01 5#012Via: SIP/2.0/TCP 37.29.170.95:33335;rport=33335;received=37.29.170.95;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias#015#012Record-Route: sip:146.185.142.19:9773;transport=tcp;lr #015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012From: < sip:+34639446862@146.185.142.19>;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abc c3d6-6314-44e0-9fc7-748f455d00b2#015#012CSeq: 21647 INVITE#015#012Contact: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob#015#012Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBS CRIBE, NOTIFY, REFER, MESSAGE, OPTIONS#015#012Supported: replaces, 100rel, timer, norefersub#015#012Session-Expires: 1800;refresher=uac#015#012Require: timer#015#012Content-Type: application/sdp#01 5#012Content-Length: 275#015#012#015#012v=0#015#012o=- 3727693379 3727693380 IN IP4 192.168.1.35#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 4002 RTP/AVP 98 96#01 5#012c=IN IP4 192.168.1.35#015#012b=TIAS:64000#015#012a=rtcp:4003 IN IP4 192.168.1.35#015#012a=sendrecv#015#012a=rtpmap:98 speex/16000#015#012a=rtpmap:96 telephone-event/8000#015#012a=fmtp:96 0-16 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:335]: update_totag_set(): DEBUG: update_totag_set: new totag Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2211]: tcpconn_send_put(): tcp connection found (0x7fa00bf44ae0), acquiring fd Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3475]: handle_ser_child(): read response= 7fa00bf44ae0, 2, fd -1 from 12 (3100) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2221]: tcpconn_send_put(): c=0x7fa00bf44ae0, n=16 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2251]: tcpconn_send_put(): after receive_fd: c= 0x7fa00bf44ae0 n=8 fd=12 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf44ae0 n=1032 fd=12 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/TCP 37.29.170.95:33335;rport=33335;receive d=37.29.170.95;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias#015#012Record-Route: sip:146.185.142.19:9773;transport=tcp;lr#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015 #012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2#015#012CSeq: 21647 IN VITE#015#012Contact: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob#015#012Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS#015#012Suppo rted: replaces, 100rel, timer, norefersub#015#012Session-Expires: 1800;refresher=uac#015#012Require: timer#015#012Content-Type: application/sdp#015#012Content-Length: 275#015#012#015#012v=0#01 5#012o=- 3727693379 3727693380 IN IP4 192.168.1.35#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 4002 RTP/AVP 98 96#015#012c=IN IP4 192.168.1.35#015#012b=TIAS:6400 0#015#012a=rtcp:4003 IN IP4 192.168.1.35#015#012a=sendrecv#015#012a=rtpmap:98 speex/16000#015#012a=rtpmap:96 telephone-event/8000#015#012a=fmtp:96 0-16 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 512, id 0 entered Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7fa00bf492c0) event type 512, reply code 200 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 0 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 1 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 2 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 3 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 4 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 5 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1591]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=0 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request: Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <ACK> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPj65ee4cd6-1345-4e0a-ba78-07849b77ab 0e>; state=6 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts... Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sl [sl_funcs.c:388]: sl_filter_ACK(): DEBUG : sl_filter_ACK: to late to be a local ACK! Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=466 a=5 n=route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=mf_process_maxfwd_header Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=25 n=is_method Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=26 n=sanity_check Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [76]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ sip:+3412345678910@146.185.142.19] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21647> <ACK> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=469 a=5 n=route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=764 a=55 n=force_rport Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=765 a=25 n=nat_uac_test Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (37.29.170.95, 37.29.170.95, 0) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=2 n=return Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=25 n=is_method Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=5 n=route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=24 n=has_totag Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=614 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=24 n=loose_route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:88]: is_preloaded(): is_preloaded: No Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:782]: after_loose(): Topmost route URI: 'sip:146.185.142.19:9773;transport=tcp;lr' is me Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:179]: find_next_route(): No next Route HF found Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:807]: after_loose(): No next URI found Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=597 a=5 n=route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=817 a=24 n=isdsturiset Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=818 a=24 n=handle_ruri_alias Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: nathelper [nathelper.c:1261]: handle_ruri_alias_f(): no alias param Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=2 n=return Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=25 n=is_method Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=602 a=25 n=is_method Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=604 a=5 n=route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=789 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=24 n=has_totag Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=788 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=25 n=check_route_param Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:985]: check_route_param(): params are <;transport=tcp;lr> Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=5 n=route Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=is_method Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=is_method Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=25 n=is_method Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=16 n=if Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=24 n=t_relay Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=11 , global msg id=10 , T on entrance=0xffffffffffffffff Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7756, isACK=1 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:279]: t_relay_to(): SER: forwarding ACK statelessly Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 0c9c2ef949368b5bf49c058f04a754ca Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:2843]: create_via_hf(): id added: <;i=5>, rcv proto=2 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:556]: forward_request(): Sending:#012ACK sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012V ia: SIP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bKc4e1.0c9c2ef949368b5bf49c058f04a754ca.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:33335 ;received=37.29.170.95;rport=33335;branch=z9hG4bKPj65ee4cd 6-1345-4e0a-ba78-07849b77ab0e;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag =8abcc3d6-6314-44e0-9fc7-748f455d00b2#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 ACK#015#012Content-Length: 0#015#012#015#012. Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:557]: forward_request(): orig. len=485, new_len=558, proto=2 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2208]: tcpconn_send_put(): found fd in cache (11, 0x7fa00bf40488, 6) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf40488 n=558 fd=11 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012ACK sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012Via : SIP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bKc4e1.0c9c2ef949368b5bf49c058f04a754ca.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:33335 ;received=37.29.170.95;rport=33335;branch=z9hG4bKPj65ee4cd6- 1345-4e0a-ba78-07849b77ab0e;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8 abcc3d6-6314-44e0-9fc7-748f455d00b2#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 ACK#015#012Content-Length: 0 Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:22:55 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=275 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <200> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <OK> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <146.185.142.19>; state=6 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKc4e1.f3913fdf4b6cd76583034cec844eb6c d.0>; state=6 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 236, <i> = <5>; state=16 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=9 global id=8 T start=0xffffffffffffffff Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <33335>; state=6 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <37.29.170.95>; state=6 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a 63>; state=6 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [78]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [< sip:+3412345678910@146.185.142.19>] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21647> <INVITE> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 7756 label 0 branch 0 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fa00bf492c0)! Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 2, id 0 entered Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7fa00bf492c0) event type 2, reply code 200 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=9 global id=9 T end=0x7fa00bf492c0 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=200, uac[0]=200 local=0 is_invite=1) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=924 a=25 n=xdbg Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <script>: incoming reply Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=927 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=926 a=5 n=route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): ->>>>>>>>> T_code=200, new_code=200 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1237]: t_should_relay_response(): DBG: t_should_relay_response: 200 INV after final sent Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=275 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [msg_translator.c:2229]: generate_res_buf_from_sip_res(): old size: 1152, new size: 1032 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [msg_translator.c:2246]: generate_res_buf_from_sip_res(): copied size: orig:136, new: 16, rest: 1016 msg=#012SIP/2.0 200 OK#01 5#012Via: SIP/2.0/TCP 37.29.170.95:33335;rport=33335;received=37.29.170.95;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias#015#012Record-Route: sip:146.185.142.19:9773;transport=tcp;lr #015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012From: < sip:+34639446862@146.185.142.19>;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abc c3d6-6314-44e0-9fc7-748f455d00b2#015#012CSeq: 21647 INVITE#015#012Contact: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob#015#012Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBS CRIBE, NOTIFY, REFER, MESSAGE, OPTIONS#015#012Supported: replaces, 100rel, timer, norefersub#015#012Session-Expires: 1800;refresher=uac#015#012Require: timer#015#012Content-Type: application/sdp#01 5#012Content-Length: 275#015#012#015#012v=0#015#012o=- 3727693379 3727693380 IN IP4 192.168.1.35#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 4002 RTP/AVP 98 96#01 5#012c=IN IP4 192.168.1.35#015#012b=TIAS:64000#015#012a=rtcp:4003 IN IP4 192.168.1.35#015#012a=sendrecv#015#012a=rtpmap:98 speex/16000#015#012a=rtpmap:96 telephone-event/8000#015#012a=fmtp:96 0-16 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:305]: update_totag_set(): DEBUG: update_totag_set: totag retransmission Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2208]: tcpconn_send_put(): found fd in cache (12, 0x7fa00bf44ae0, 5) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf44ae0 n=1032 fd=12 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/TCP 37.29.170.95:33335;rport=33335;received=3 7.29.170.95;branch=z9hG4bKPjd8e1dc7c-4dd1-42ac-88e4-b09e0cc55a63;alias#015#012Record-Route: sip:146.185.142.19:9773;transport=tcp;lr#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012Fr om: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2#015#012CSeq: 21647 INVITE#015# 012Contact: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob#015#012Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS#015#012Supported: replac es, 100rel, timer, norefersub#015#012Session-Expires: 1800;refresher=uac#015#012Require: timer#015#012Content-Type: application/sdp#015#012Content-Length: 275#015#012#015#012v=0#015#012o=- 372769 3379 3727693380 IN IP4 192.168.1.35#015#012s=pjmedia#015#012b=AS:84#015#012t=0 0#015#012a=X-nat:0#015#012m=audio 4002 RTP/AVP 98 96#015#012c=IN IP4 192.168.1.35#015#012b=TIAS:64000#015#012a=rtcp:40 03 IN IP4 192.168.1.35#015#012a=sendrecv#015#012a=rtpmap:98 speex/16000#015#012a=rtpmap:96 telephone-event/8000#015#012a=fmtp:96 0-16 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=0 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request: Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <ACK> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPj65ee4cd6-1345-4e0a-ba78-07849b77ab0e> ; state=6 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts... Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sl [sl_funcs.c:388]: sl_filter_ACK(): DEBUG : sl_filter_ACK: to late to be a local ACK! Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=466 a=5 n=route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=mf_process_maxfwd_header Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=25 n=is_method Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=26 n=sanity_check Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [76]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ sip:+3412345678910@146.185.142.19] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21647> <ACK> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=469 a=5 n=route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=764 a=55 n=force_rport Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=765 a=25 n=nat_uac_test Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (37.29.170.95, 37.29.170.95, 0) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=2 n=return Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=25 n=is_method Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=5 n=route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=24 n=has_totag Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=614 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=24 n=loose_route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:88]: is_preloaded(): is_preloaded: No Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:782]: after_loose(): Topmost route URI: 'sip:146.185.142.19:9773;transport=tcp;lr' is me Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:179]: find_next_route(): No next Route HF found Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:807]: after_loose(): No next URI found Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=597 a=5 n=route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=817 a=24 n=isdsturiset Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=818 a=24 n=handle_ruri_alias Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: nathelper [nathelper.c:1261]: handle_ruri_alias_f(): no alias param Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=2 n=return Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=25 n=is_method Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=602 a=25 n=is_method Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=604 a=5 n=route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=789 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=24 n=has_totag Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=788 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=25 n=check_route_param Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:985]: check_route_param(): params are <;transport=tcp;lr> Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=5 n=route Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=is_method Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=is_method Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=25 n=is_method Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=16 n=if Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=24 n=t_relay Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=12 , global msg id=11 , T on entrance=(nil) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7756, isACK=1 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:279]: t_relay_to(): SER: forwarding ACK statelessly Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 0c9c2ef949368b5bf49c058f04a754ca Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:2843]: create_via_hf(): id added: <;i=5>, rcv proto=2 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:556]: forward_request(): Sending:#012ACK sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012Via: SIP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bKc4e1.0c9c2ef949368b5bf49c058f04a754ca.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:33335 ;received=37.29.170.95;rport=33335;branch=z9hG4bKPj65ee4cd6-1345 -4e0a-ba78-07849b77ab0e;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6 -6314-44e0-9fc7-748f455d00b2#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 ACK#015#012Content-Length: 0#015#012#015#012. Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:557]: forward_request(): orig. len=485, new_len=558, proto=2 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2208]: tcpconn_send_put(): found fd in cache (11, 0x7fa00bf40488, 6) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf40488 n=558 fd=11 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012ACK sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012Via: S IP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bKc4e1.0c9c2ef949368b5bf49c058f04a754ca.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:33335 ;received=37.29.170.95;rport=33335;branch=z9hG4bKPj65ee4cd6-1345-4 e0a-ba78-07849b77ab0e;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6-6 314-44e0-9fc7-748f455d00b2#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21647 ACK#015#012Content-Length: 0 Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:22:56 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:23:00 kamailio-alone /usr/sbin/kamailio[3094]: DEBUG: tm [h_table.c:126]: free_cell_helper(): freeing transaction 0x7fa00bf492c0 from timer.c:648 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request: Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <BYE> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPj53d658e0-c4c1-4897-b515-dd62acfa5a9b> ; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=466 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=mf_process_maxfwd_header Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=26 n=sanity_check Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [76]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ sip:+3412345678910@146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21648> <BYE> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=469 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=764 a=55 n=force_rport Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=765 a=25 n=nat_uac_test Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (37.29.170.95, 37.29.170.95, 0) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=2 n=return Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=24 n=has_totag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=614 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=24 n=loose_route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:88]: is_preloaded(): is_preloaded: No Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:782]: after_loose(): Topmost route URI: 'sip:146.185.142.19:9773;transport=tcp;lr' is me Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:179]: find_next_route(): No next Route HF found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:807]: after_loose(): No next URI found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=597 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=817 a=24 n=isdsturiset Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=818 a=24 n=handle_ruri_alias Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: nathelper [nathelper.c:1261]: handle_ruri_alias_f(): no alias param Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=2 n=return Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=599 a=39 n=setflag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=600 a=39 n=setflag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_is_set Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_on_branch Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=24 n=t_relay Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=13 , global msg id=12 , T on entrance=(nil) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7770, isACK=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 1, id 0 entered Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 394dbdec3e45a97d62682f54a161512a Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=918 a=25 n=xdbg Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <script>: new branch [0] to sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=919 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=789 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=24 n=has_totag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=788 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=25 n=check_route_param Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:985]: check_route_param(): params are <;transport=tcp;lr> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:2843]: create_via_hf(): id added: <;i=5>, rcv proto=2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2208]: tcpconn_send_put(): found fd in cache (11, 0x7fa00bf40488, 6) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf40488 n=558 fd=11 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012BYE sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012Via: S IP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bKa5e1.394dbdec3e45a97d62682f54a161512a.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:33335 ;received=37.29.170.95;rport=33335;branch=z9hG4bKPj53d658e0-c4c1-4 897-b515-dd62acfa5a9b;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6-6 314-44e0-9fc7-748f455d00b2#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21648 BYE#015#012Content-Length: 0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:362]: t_relay_to(): SER: new transaction fwd'ed Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=2 n=exit Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <200> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <OK> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <146.185.142.19>; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKa5e1.394dbdec3e45a97d62682f54a161512a.0

; state=6

Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 236, <i> = <5>; state=16 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global id=9 T start=0xffffffffffffffff Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <33335>; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <37.29.170.95>; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPj53d658e0-c4c1-4897-b515-dd62acfa5a9b> ; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [78]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [< sip:+3412345678910@146.185.142.19>] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21648> <BYE> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 7770 label 0 branch 0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fa00bf492c0)! Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 2, id 0 entered Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7fa00bf492c0) event type 2, reply code 200 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global id=10 T end=0x7fa00bf492c0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [msg_translator.c:2229]: generate_res_buf_from_sip_res(): old size: 516, new size: 396 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [msg_translator.c:2246]: generate_res_buf_from_sip_res(): copied size: orig:136, new: 16, rest: 380 msg=#012SIP/2.0 200 OK#015 #012Via: SIP/2.0/TCP 37.29.170.95:33335;rport=33335;received=37.29.170.95;branch=z9hG4bKPj53d658e0-c4c1-4897-b515-dd62acfa5a9b;alias#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012From : sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2#015#012CSeq: 21648 BYE#015#012Co ntent-Length: 0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2208]: tcpconn_send_put(): found fd in cache (12, 0x7fa00bf44ae0, 5) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf44ae0 n=396 fd=12 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/TCP 37.29.170.95:33335;rport=33335;received=3 7.29.170.95;branch=z9hG4bKPj53d658e0-c4c1-4897-b515-dd62acfa5a9b;alias#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012From: < sip:+34639446862@146.185.142.19>;tag=d48d0318-ea78-44ed-90b 7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2#015#012CSeq: 21648 BYE#015#012Content-Length: 0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7fa00bf492c0, callback type 512, id 0 entered Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7fa00bf492c0) event type 512, reply code 200 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 1 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 3 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 4 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 5 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: tm [t_reply.c:1591]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1339]: tcp_read_req(): content-length=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request: Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <BYE> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjfefd5df3-8e04-49fe-a0d9-83a831218f67> ; state=6 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=466 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=mf_process_maxfwd_header Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=579 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=26 n=sanity_check Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [76]; uri=[sip:+3412345678910@146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ sip:+3412345678910@146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <21649> <BYE> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=469 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=764 a=55 n=force_rport Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=765 a=25 n=nat_uac_test Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (37.29.170.95, 37.29.170.95, 0) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=775 a=2 n=return Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=480 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=24 n=has_totag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=614 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=596 a=24 n=loose_route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:88]: is_preloaded(): is_preloaded: No Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 12==14 && [88.15.197.56] == [146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 55288 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [forward.c:412]: check_self(): host != me Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:782]: after_loose(): Topmost route URI: 'sip:146.185.142.19:9773;transport=tcp;lr' is me Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:179]: find_next_route(): No next Route HF found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:807]: after_loose(): No next URI found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=597 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=817 a=24 n=isdsturiset Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=818 a=24 n=handle_ruri_alias Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: nathelper [nathelper.c:1261]: handle_ruri_alias_f(): no alias param Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[DLGURI] c=[/etc/kamailio/kamailio.cfg] l=821 a=2 n=return Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=599 a=39 n=setflag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=600 a=39 n=setflag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=610 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_is_set Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=25 n=t_on_branch Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=25 n=is_method Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=24 n=t_relay Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=14 , global msg id=13 , T on entrance=0xffffffffffffffff Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=7769, isACK=0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7fa00bf4d808, callback type 1, id 0 entered Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 93ce403dfdc7700e8dc7cfa7395378b8 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=918 a=25 n=xdbg Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <script>: new branch [0] to sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=919 a=5 n=route Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=24 n=is_request Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=789 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=24 n=has_totag Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: siputils [checks.c:100]: has_totag(): totag found Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=788 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=25 n=check_route_param Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: rr [loose.c:985]: check_route_param(): params are <;transport=tcp;lr> Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=41 n=isflagset Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=791 a=25 n=isbflagset Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=792 a=2 n=return Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [msg_translator.c:2843]: create_via_hf(): id added: <;i=5>, rcv proto=2 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2208]: tcpconn_send_put(): found fd in cache (11, 0x7fa00bf40488, 6) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf40488 n=558 fd=11 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012BYE sip:+3412345678910@88.15.197.56:55288;transport=TCP;ob SIP/2.0#015#012Via: S IP/2.0/TCP 146.185.142.19:9773;branch=z9hG4bK95e1.93ce403dfdc7700e8dc7cfa7395378b8.0;i=5#015#012Via: SIP/2.0/TCP 37.29.170.95:33335 ;received=37.29.170.95;rport=33335;branch=z9hG4bKPjfefd5df3-8e04-4 9fe-a0d9-83a831218f67;alias#015#012Max-Forwards: 69#015#012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+3412345678910@146.185.142.19;tag=8abcc3d6-6 314-44e0-9fc7-748f455d00b2#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21649 BYE#015#012Content-Length: 0 Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: tm [t_funcs.c:362]: t_relay_to(): SER: new transaction fwd'ed Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=2 n=exit Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Feb 15 14:23:01 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Feb 15 14:23:03 kamailio-alone /usr/sbin/kamailio[3100]: ERROR: <core> [tcp_read.c:274]: tcp_read_data(): error reading: Connection reset by peer (104) ([88.15.197.56]:55288 -> [88.15.197.56]:9773) Feb 15 14:23:03 kamailio-alone /usr/sbin/kamailio[3100]: ERROR: <core> [tcp_read.c:1303]: tcp_read_req(): ERROR: tcp_read_req: error reading Feb 15 14:23:03 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [io_wait.h:598]: io_watch_del(): DBG: io_watch_del (0xa81660, 9, -1, 0x10) fd_no=2 called Feb 15 14:23:03 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1460]: release_tcpconn(): releasing con 0x7fa00bf40488, state -2, fd=9, id=6 ([88.15.197.56]:55288 -> [88.15.197.5 6]:9773) Feb 15 14:23:03 kamailio-alone /usr/sbin/kamailio[3100]: DEBUG: <core> [tcp_read.c:1464]: release_tcpconn(): extra_data (nil) Feb 15 14:23:03 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3242]: handle_tcp_child(): reader response= 7fa00bf40488, -2 from 2 Feb 15 14:23:06 kamailio-alone /usr/sbin/kamailio[3094]: DEBUG: tm [h_table.c:126]: free_cell_helper(): freeing transaction 0x7fa00bf492c0 from timer.c:648 Feb 15 14:23:07 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [io_wait.h:598]: io_watch_del(): DBG: io_watch_del (0xa81660, 9, -1, 0x10) fd_no=2 called Feb 15 14:23:07 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1460]: release_tcpconn(): releasing con 0x7fa00bf44ae0, state 1, fd=9, id=5 ([37.29.170.95]:33335 -> [37.29.170.95 ]:9773) Feb 15 14:23:07 kamailio-alone /usr/sbin/kamailio[3099]: DEBUG: <core> [tcp_read.c:1464]: release_tcpconn(): extra_data (nil) Feb 15 14:23:07 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3242]: handle_tcp_child(): reader response= 7fa00bf44ae0, 1 from 1 Feb 15 14:23:07 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [io_wait.h:376]: io_watch_add(): DBG: io_watch_add(0xa28d40, 29, 2, 0x7fa00bf44ae0), fd_no=19 Feb 15 14:23:07 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3369]: handle_tcp_child(): CONN_RELEASE 0x7fa00bf44ae0 refcnt= 1 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=408 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: <core> [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: <core> [tcp_main.c:2211]: tcpconn_send_put(): tcp connection found (0x7fa00bf44ae0), acquiring fd Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: <core> [tcp_main.c:2221]: tcpconn_send_put(): c=0x7fa00bf44ae0, n=16 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3102]: DEBUG: <core> [tcp_main.c:3475]: handle_ser_child(): read response= 7fa00bf44ae0, 2, fd -1 from 5 (3093) Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: <core> [tcp_main.c:2251]: tcpconn_send_put(): after receive_fd: c= 0x7fa00bf44ae0 n=8 fd=5 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending... Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fa00bf44ae0 n=445 fd=5 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012SIP/2.0 408 Request Timeout#015#012Via: SIP/2.0/TCP 37.29.170.95:33335;rport=333 35;branch=z9hG4bKPjfefd5df3-8e04-49fe-a0d9-83a831218f67;alias;received=37.29.170.95#015#012From: sip:+34639446862@146.185.142.19;tag=d48d0318-ea78-44ed-90b7-0634b2ee4f40#015#012To: sip:+34123456789 10@146.185.142.19;tag=8abcc3d6-6314-44e0-9fc7-748f455d00b2#015#012Call-ID: 167f1514-e91c-42c7-8767-afc78c31abda#015#012CSeq: 21649 BYE#015#012Server: kamailio (4.3.4 (x86_64/linux))#015#012Content- Length: 0 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7fa00bf4d808, callback type 512, id 0 entered Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7fa00bf4d808) event type 512, reply code 408 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 0 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 1 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 2 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 3 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 4 Feb 15 14:23:31 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 5 Feb 15 14:23:36 kamailio-alone /usr/sbin/kamailio[3094]: DEBUG: tm [h_table.c:126]: free_cell_helper(): freeing transaction 0x7fa00bf4d808 from timer.c:648 Feb 15 14:24:14 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: usrloc [urecord.c:382]: wb_timer(): Binding '+34639446862','sip:+34639446862@88.15.197.56:40543;transport=TCP;ob' has expired Feb 15 14:24:14 kamailio-alone /usr/sbin/kamailio[3093]: DEBUG: usrloc [ucontact.c:1787]: uldb_delete_attrs_ruid(): trying to delete location attributes

As you can see check_route_param(): params are <;transport=tcp;lr>

2018-02-15 18:16 GMT+01:00 Daniel-Constantin Mierla miconda@gmail.com:

Based on the logs, there is no check_route_param() executed. Actually, it is an initial INVITE, doesn't have a to-tag, so there is no Route header added previously by kamailio where check_route_param() can work on.

Looking at the headers, this initial INVITE has Via and Contact with public addresses (maybe UA is using stun), only the SDP seems to have carrier grand nat address. You have to adjust the parameter for nat_uac_test() in order to detect it is a natted call.

Cheers, Daniel

On 15.02.18 15:35, Pau Giralt wrote:

My kamailio version is:

version: kamailio 4.4.6 (x86_64/linux) becbde flags: STATS: Off, USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, SHM_MMAP, PKG_MALLOC, Q_MALLOC, F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, 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 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: becbde compiled on 08:18:45 Jul 3 2017 with gcc 5.4.0

I've pasted the logs below.

( ... )

[...]

Thanks!

2018-02-15 15:02 GMT+01:00 Daniel-Constantin Mierla miconda@gmail.com:

...

Hello,

first, what is the version of kamailio?

Then, can you load debugger module and set cfgtrace=1, then send here all log messages for a case exposing this issue? It should show what actions are executed from config.

Cheers, Daniel

On 15.02.18 12:08, Pau Giralt wrote:

Hi!

I'm working with kamailio and rtpproxy. When I configure the clients to send sip messages through TCP adding the ";transport=tcp" in the sip message the rtpproxy is not working anymore.

The code is never passing the point pasted below, inside route[NATMANAGE] in kamailio.cfg

if (!(isflagset(FLT_NATS) || isbflagset(FLB_NATB))) return;

Therefore, rtpproxy_manage("co"); is never reached.

Could it be a parsing problem? ( check_route_param("nat=yes") )

The config file is exactly the same as the one provided in the kamailio repository (the default one)

Thanks!

---

Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio Advanced Training - March 5-7, 2018, Berlin - www.asipto.com Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com

-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio Advanced Training - March 5-7, 2018, Berlin - www.asipto.com Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com

Sorry I don't have a deep knowledge in SIP protocol either how kamailio works internally.

These are the invite messages:

T 88.15.197.56:34204 -> 146.185.142.19:9773 [AP] INVITE sip:+34639446862@146.185.142.19:9773 SIP/2.0. Via: SIP/2.0/TCP 88.15.197.56:34204 ;rport;branch=z9hG4bKPj4778a63e-b3a1-4908-99b9-524161085409;alias. Max-Forwards: 70. From: sip:+3412345678910@146.185.142.19 ;tag=9db4d38c-badb-41fe-8910-44857fb759c1. To: sip:+34639446862@146.185.142.19. Contact: sip:+3412345678910@88.15.197.56:34204;transport=TCP;ob. Call-ID: 4c2fcd40-9b71-4dcf-b2dd-ee85318883f9. CSeq: 32366 INVITE. Route: sip:146.185.142.19:9773;transport=tcp;lr. Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS. Supported: replaces, 100rel, timer, norefersub. Session-Expires: 1800. Min-SE: 90. Content-Type: application/sdp. Content-Length: 473. . v=0. o=- 3727764645 3727764645 IN IP4 192.168.1.35. s=pjmedia. b=AS:84. t=0 0. a=X-nat:0. m=audio 4002 RTP/AVP 98 97 99 104 3 0 8 9 96. c=IN IP4 192.168.1.35. b=TIAS:64000. a=rtcp:4003 IN IP4 192.168.1.35. a=sendrecv. a=rtpmap:98 speex/16000. a=rtpmap:97 speex/8000. a=rtpmap:99 speex/32000. a=rtpmap:104 iLBC/8000. a=fmtp:104 mode=30. a=rtpmap:3 GSM/8000. a=rtpmap:0 PCMU/8000. a=rtpmap:8 PCMA/8000. a=rtpmap:9 G722/8000. a=rtpmap:96 telephone-event/8000. a=fmtp:96 0-16.

# T 146.185.142.19:9773 -> 88.15.197.56:34204 [AP] SIP/2.0 100 trying -- your call is important to us. Via: SIP/2.0/TCP 88.15.197.56:34204 ;rport=34204;branch=z9hG4bKPj4778a63e-b3a1-4908-99b9-524161085409;alias;received=88.15.197.56. From: sip:+3412345678910@146.185.142.19 ;tag=9db4d38c-badb-41fe-8910-44857fb759c1. To: sip:+34639446862@146.185.142.19. Call-ID: 4c2fcd40-9b71-4dcf-b2dd-ee85318883f9. CSeq: 32366 INVITE. Server: kamailio (4.3.4 (x86_64/linux)). Content-Length: 0. .

# T 146.185.142.19:9773 -> 31.221.250.100:52775 [AP] INVITE sip:+34639446862@31.221.250.100:52775;transport=TCP;ob SIP/2.0. Record-Route: sip:146.185.142.19:9773;transport=tcp;lr. Via: SIP/2.0/TCP 146.185.142.19:9773 ;branch=z9hG4bK59dc.a677b7aceca06187ebd35e44c30f42a4.0;i=3. Via: SIP/2.0/TCP 88.15.197.56:34204 ;received=88.15.197.56;rport=34204;branch=z9hG4bKPj4778a63e-b3a1-4908-99b9-524161085409;alias. Max-Forwards: 69. From: sip:+3412345678910@146.185.142.19 ;tag=9db4d38c-badb-41fe-8910-44857fb759c1. To: sip:+34639446862@146.185.142.19. Contact: sip:+3412345678910@88.15.197.56:34204;transport=TCP;ob. Call-ID: 4c2fcd40-9b71-4dcf-b2dd-ee85318883f9. CSeq: 32366 INVITE. Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS. Supported: replaces, 100rel, timer, norefersub. Session-Expires: 1800. Min-SE: 90. Content-Type: application/sdp. Content-Length: 473. . v=0. o=- 3727764645 3727764645 IN IP4 192.168.1.35. s=pjmedia. b=AS:84. t=0 0. a=X-nat:0. m=audio 4002 RTP/AVP 98 97 99 104 3 0 8 9 96. c=IN IP4 192.168.1.35. b=TIAS:64000. a=rtcp:4003 IN IP4 192.168.1.35. a=sendrecv. a=rtpmap:98 speex/16000. a=rtpmap:97 speex/8000. a=rtpmap:99 speex/32000. a=rtpmap:104 iLBC/8000. a=fmtp:104 mode=30. a=rtpmap:3 GSM/8000. a=rtpmap:0 PCMU/8000. a=rtpmap:8 PCMA/8000. a=rtpmap:9 G722/8000. a=rtpmap:96 telephone-event/8000. a=fmtp:96 0-16.

The Via and Contact both have the public ip, it's weird because I'm not using STUN in clients. The route and the record route are the sip domain with the transport tag. sip:146.185.142.19:9773;transport=tcp;lr. So how it should be to be market as "natted" and be sent to rtpproxy? Thanks for your patience.

2018-02-16 11:06 GMT+01:00 Daniel-Constantin Mierla miconda@gmail.com:

Wondering if you actually read properly my previous response. There is no "nat=yes" in Route params because the call (via initial INVITE) is not marked as natted -- look at what is sent to the network for the initial INVITE in the Record-Route header.

Daniel

On 16.02.18 10:45, Pau Giralt wrote:

These are the complete logs for the previous call:

[...]

As you can see check_route_param(): params are <;transport=tcp;lr>

2018-02-15 18:16 GMT+01:00 Daniel-Constantin Mierla miconda@gmail.com:

...

Based on the logs, there is no check_route_param() executed. Actually, it is an initial INVITE, doesn't have a to-tag, so there is no Route header added previously by kamailio where check_route_param() can work on.

Looking at the headers, this initial INVITE has Via and Contact with public addresses (maybe UA is using stun), only the SDP seems to have carrier grand nat address. You have to adjust the parameter for nat_uac_test() in order to detect it is a natted call.

Cheers, Daniel

On 15.02.18 15:35, Pau Giralt wrote:

My kamailio version is:

version: kamailio 4.4.6 (x86_64/linux) becbde flags: STATS: Off, USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, SHM_MMAP, PKG_MALLOC, Q_MALLOC, F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, 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 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: becbde compiled on 08:18:45 Jul 3 2017 with gcc 5.4.0

I've pasted the logs below.

( ... )

[...]

Thanks!

2018-02-15 15:02 GMT+01:00 Daniel-Constantin Mierla miconda@gmail.com:

...

Hello,

first, what is the version of kamailio?

Then, can you load debugger module and set cfgtrace=1, then send here all log messages for a case exposing this issue? It should show what actions are executed from config.

Cheers, Daniel

On 15.02.18 12:08, Pau Giralt wrote:

Hi!

I'm working with kamailio and rtpproxy. When I configure the clients to send sip messages through TCP adding the ";transport=tcp" in the sip message the rtpproxy is not working anymore.

The code is never passing the point pasted below, inside route[NATMANAGE] in kamailio.cfg

if (!(isflagset(FLT_NATS) || isbflagset(FLB_NATB))) return;

Therefore, rtpproxy_manage("co"); is never reached.

Could it be a parsing problem? ( check_route_param("nat=yes") )

The config file is exactly the same as the one provided in the kamailio repository (the default one)

Thanks!

---

Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio Advanced Training - March 5-7, 2018, Berlin - www.asipto.com Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com

-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio Advanced Training - March 5-7, 2018, Berlin - www.asipto.com Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com

-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio Advanced Training - March 5-7, 2018, Berlin - www.asipto.com Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com