<p>this is a more accurate debug trace:</p>
<pre><code>Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():  method:  <INVITE>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():  uri:     <sip:+3481818181@146.185.142.19:9773>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  version: <SIP/2.0>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157>; state=16
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg...
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts...
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=462 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=575 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=570 a=25 n=mf_process_maxfwd_header
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=580 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=580 a=26 n=sanity_check
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=9
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [32]; uri=[sip:+3481818181@146.185.142.19]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [sip:+3481818181@146.185.142.19#015#012]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <13850> <INVITE>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=476
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=42538f71-1d62-45a9-b994-bdb5876d7c3a
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=465 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=760 a=55 n=force_rport
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=771 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=761 a=25 n=nat_uac_test
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (46.222.206.146, 46.222.206.146, 0)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=771 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=476 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=468 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=476 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=588 a=24 n=has_totag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: siputils [checks.c:97]: has_totag(): no totag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=588 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=485 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=481 a=24 n=t_precheck_trans
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=485 a=24 n=t_check_trans
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=2 T start=0xffffffffffffffff
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=17941, isACK=0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T end=(nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=488 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=748 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=735 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [forward.c:412]: check_self(): host != me
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=754 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [forward.c:412]: check_self(): host != me
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=754 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=492 a=25 n=remove_hf
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=497 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=493 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=494 a=24 n=record_route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=502 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=497 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=498 a=39 n=setflag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=502 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=820 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 && [146.185.142.19] == [146.185.142.19]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=818 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=507 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=693 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=690 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=691 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=510 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=635 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=633 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=633 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=519 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=519 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PSTN] c=[/etc/kamailio/kamailio.cfg] l=857 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=522 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=663 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=659 a=25 n=alias_db_lookup
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:115]: db_new_result(): allocate 56 bytes for result set at 0x7f7db1e94d90
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 2 columns returned from the query
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:152]: db_allocate_columns(): allocate 16 bytes for result names at 0x7f7db1e93cf0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:163]: db_allocate_columns(): allocate 8 bytes for result types at 0x7f7db1e93d60
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f7db1e6ec88
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f7db1e6ec88)[0]=[username]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7f7db1e6f840
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f7db1e6f840)[1]=[domain]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:168]: db_mysql_convert_rows(): no rows returned from the query
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: alias_db [alookup.c:117]: alias_db_lookup(): no alias found for R-URI
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 2 columns
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f7db1e6ec88
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7f7db1e6f840
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:92]: db_free_columns(): freeing result names at 0x7f7db1e93cf0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:97]: db_free_columns(): freeing result types at 0x7f7db1e93d60
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [db_res.c:134]: db_free_result(): freeing result set at 0x7f7db1e94d90
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=663 a=63 n=assign
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=680 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=664 a=25 n=lookup
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: registrar [lookup.c:196]: lookup_helper(): contact for [+3481818181] found by address
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=680 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=681 a=39 n=setflag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=533 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=530 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=532 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=531 a=25 n=t_is_set
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=531 a=25 n=t_on_branch
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=533 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=t_is_set
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=t_on_reply
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=25 n=is_method
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=538 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=t_is_set
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=t_on_failure
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=543 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=24 n=t_relay
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=3 , global msg id=3 , T on entrance=(nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=17941, isACK=0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 1, id 0 entered
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 0db5f769d2a6f8e22f43cd18dc188e56
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_funcs.c:315]: t_relay_to(): SER: new INVITE
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_reply.c:647]: _reply_light(): DEBUG: reply sent out. buf=0x7f7db1e94ec0: SIP/2.0 100 trying -..., shmem=0x7f7da8021f08: SIP/2.0 100 trying -
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_reply.c:657]: _reply_light(): DEBUG: _reply_light: finished
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=910 a=25 n=xdbg
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <script>: new branch [0] to sip:+3481818181@88.5.4.230:9773;ob
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=911 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=777 a=24 n=is_request
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=778 a=24 n=has_totag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: siputils [checks.c:97]: has_totag(): no totag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=787 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=41 n=isflagset
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=25 n=isbflagset
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_funcs.c:362]: t_relay_to(): SER: new transaction fwd'ed
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=543 a=2 n=exit
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply  (status):
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg():  version: <SIP/2.0>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg():  status:  <100>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg():  reason:  <Trying>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <146.185.142.19>; state=6
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK5164.0db5f769d2a6f8e22f43cd18dc188e56.0>; state=16
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg...
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=(nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <44637>; state=6
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <46.222.206.146>; state=6
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157>; state=16
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [34]; uri=[sip:+3481818181@146.185.142.19]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [<sip:+3481818181@146.185.142.19>#015#012]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <13850> <INVITE>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 17941 label 0 branch 0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f7da801f350)!
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 2, id 0 entered
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 2, reply code 100
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f7da801f350
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=916 a=25 n=xdbg
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <script>: incoming reply
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=919 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=918 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=777 a=24 n=is_request
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=787 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=41 n=isflagset
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=25 n=isbflagset
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=100
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=-1 icode=0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply  (status):
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg():  version: <SIP/2.0>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg():  status:  <500>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg():  reason:  <>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <146.185.142.19>; state=6
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK5164.0db5f769d2a6f8e22f43cd18dc188e56.0>; state=16
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg...
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=(nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <44637>; state=6
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <46.222.206.146>; state=6
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157>; state=16
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=65e14bd6-04f8-4235-8fb1-5bf452827546
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [75]; uri=[sip:+3481818181@146.185.142.19]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [<sip:+3481818181@146.185.142.19>]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <13850> <INVITE>
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 17941 label 0 branch 0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f7da801f350)!
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 2, id 0 entered
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 2, reply code 500
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f7da801f350
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=916 a=25 n=xdbg
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <script>: incoming reply
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=919 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=500
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 128, id 0 entered
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 128, reply code 500
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: NOTICE: acc [acc.c:315]: acc_log_request(): ACC: call missed: timestamp=1515761540;method=INVITE;from_tag=42538f71-1d62-45a9-b994-bdb5876d7c3a;to_tag=65e14bd6-04f8-4235-8fb1-5bf452827546;call_id=0ba83c1c-1b50-4903-8f7c-ee602525316e;code=500;reason=;src_user=+34639446862;src_domain=146.185.142.19;src_ip=46.222.206.146;dst_ouser=+3481818181;dst_user=+3481818181;dst_domain=88.5.4.230
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 1
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 2
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 3
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 4
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 5
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[MANAGE_FAILURE] c=[/etc/kamailio/kamailio.cfg] l=923 a=5 n=route
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=777 a=24 n=is_request
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=778 a=24 n=has_totag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: siputils [checks.c:97]: has_totag(): no totag
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=787 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=41 n=isflagset
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=25 n=isbflagset
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=2 n=return
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[MANAGE_FAILURE] c=[/etc/kamailio/kamailio.cfg] l=946 a=16 n=if
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[MANAGE_FAILURE] c=[/etc/kamailio/kamailio.cfg] l=925 a=24 n=t_is_canceled
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T start=0x7f7da801f350
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found!
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [msg_translator.c:2229]: generate_res_buf_from_sip_res(): old size: 552, new size: 436
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [msg_translator.c:2246]: generate_res_buf_from_sip_res(): copied size: orig:130, new: 14, rest: 422 msg=#012SIP/2.0 500 #015#012Via: SIP/2.0/UDP 46.222.206.146:44637;rport=44637;received=46.222.206.146;branch=z9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157#015#012Record-Route: <sip:146.185.142.19:9773;lr>#015#012Call-ID: 0ba83c1c-1b50-4903-8f7c-ee602525316e#015#012From: <sip:+34639446862@146.185.142.19>;tag=42538f71-1d62-45a9-b994-bdb5876d7c3a#015#012To: <sip:+3481818181@146.185.142.19>;tag=65e14bd6-04f8-4235-8fb1-5bf452827546#015#012CSeq: 13850 INVITE#015#012Content-Length:  0
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 512, id 0 entered
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 512, reply code 500
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:1591]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)]
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)
Jan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up
</code></pre>
<p>The 500 code seems to be sent indeed by client (using pjsip).</p>

<p style="font-size:small;-webkit-text-size-adjust:none;color:#666;">—<br />You are receiving this because you are subscribed to this thread.<br />Reply to this email directly, <a href="https://github.com/kamailio/kamailio/issues/1395#issuecomment-357233382">view it on GitHub</a>, or <a href="https://github.com/notifications/unsubscribe-auth/AF36ZZhagUou-WWD93M5RmXMfD7tg2Wyks5tJ1ingaJpZM4Ra5Dq">mute the thread</a>.<img alt="" height="1" src="https://github.com/notifications/beacon/AF36ZaXDte1VK5y4iQtT-0mQpGm5cB1Jks5tJ1ingaJpZM4Ra5Dq.gif" width="1" /></p>
<div itemscope itemtype="http://schema.org/EmailMessage">
<div itemprop="action" itemscope itemtype="http://schema.org/ViewAction">
  <link itemprop="url" href="https://github.com/kamailio/kamailio/issues/1395#issuecomment-357233382"></link>
  <meta itemprop="name" content="View Issue"></meta>
</div>
<meta itemprop="description" content="View this Issue on GitHub"></meta>
</div>

<script type="application/json" data-scope="inboxmarkup">{"api_version":"1.0","publisher":{"api_key":"05dde50f1d1a384dd78767c55493e4bb","name":"GitHub"},"entity":{"external_key":"github/kamailio/kamailio","title":"kamailio/kamailio","subtitle":"GitHub repository","main_image_url":"https://cloud.githubusercontent.com/assets/143418/17495839/a5054eac-5d88-11e6-95fc-7290892c7bb5.png","avatar_image_url":"https://cloud.githubusercontent.com/assets/143418/15842166/7c72db34-2c0b-11e6-9aed-b52498112777.png","action":{"name":"Open in GitHub","url":"https://github.com/kamailio/kamailio"}},"updates":{"snippets":[{"icon":"PERSON","message":"@kelf47 in #1395: this is a more accurate debug trace:\r\n\r\n```\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:606]: parse_msg(): SIP Request:\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:608]: parse_msg():  method:  \u003cINVITE\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:610]: parse_msg():  uri:     \u003csip:+3481818181@146.185.142.19:9773\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:612]: parse_msg():  version: \u003cSIP/2.0\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, \u003crport\u003e = \u003cn/a\u003e; state=6\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, \u003cbranch\u003e = \u003cz9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157\u003e; state=16\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [receive.c:134]: receive_msg(): After parse_msg...\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [receive.c:177]: receive_msg(): preparing to run routing scripts...\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=462 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=575 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=570 a=25 n=mf_process_maxfwd_header\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=580 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=584 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=580 a=26 n=sanity_check\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=9\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: \u003cTo\u003e [32]; uri=[sip:+3481818181@146.185.142.19]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [sip:+3481818181@146.185.142.19#015#012]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq \u003cCSeq\u003e: \u003c13850\u003e \u003cINVITE\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=476\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:89]: get_hdr_field(): found end of header\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=42538f71-1d62-45a9-b994-bdb5876d7c3a\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=465 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=760 a=55 n=force_rport\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=771 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=761 a=25 n=nat_uac_test\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [msg_translator.c:158]: check_via_address(): (46.222.206.146, 46.222.206.146, 0)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=771 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=476 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=468 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=476 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=592 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=588 a=24 n=has_totag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: siputils [checks.c:97]: has_totag(): no totag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=588 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=485 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=481 a=24 n=t_precheck_trans\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=485 a=24 n=t_check_trans\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=2 T start=0xffffffffffffffff\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=17941, isACK=0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T end=(nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=488 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=748 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=735 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 \u0026\u0026 [146.185.142.19] == [146.185.142.19]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [forward.c:412]: check_self(): host != me\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=754 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 \u0026\u0026 [146.185.142.19] == [146.185.142.19]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 5060\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [forward.c:412]: check_self(): host != me\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 \u0026\u0026 [146.185.142.19] == [146.185.142.19]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=754 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=492 a=25 n=remove_hf\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=497 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=493 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=494 a=24 n=record_route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=502 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=497 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=498 a=39 n=setflag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=502 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=820 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:558]: grep_sock_info(): checking if host==us: 14==14 \u0026\u0026 [146.185.142.19] == [146.185.142.19]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [socket_info.c:565]: grep_sock_info(): checking if port 9773 (advertise 0) matches port 9773\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=818 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=507 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=693 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=690 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=691 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=510 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=635 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=633 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=633 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=519 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=519 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[PSTN] c=[/etc/kamailio/kamailio.cfg] l=857 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=522 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=663 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=659 a=25 n=alias_db_lookup\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:115]: db_new_result(): allocate 56 bytes for result set at 0x7f7db1e94d90\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 2 columns returned from the query\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:152]: db_allocate_columns(): allocate 16 bytes for result names at 0x7f7db1e93cf0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:163]: db_allocate_columns(): allocate 8 bytes for result types at 0x7f7db1e93d60\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f7db1e6ec88\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f7db1e6ec88)[0]=[username]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7f7db1e6f840\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f7db1e6f840)[1]=[domain]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: db_mysql [km_res.c:168]: db_mysql_convert_rows(): no rows returned from the query\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: alias_db [alookup.c:117]: alias_db_lookup(): no alias found for R-URI\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:79]: db_free_columns(): freeing 2 columns\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f7db1e6ec88\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7f7db1e6f840\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:92]: db_free_columns(): freeing result names at 0x7f7db1e93cf0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:97]: db_free_columns(): freeing result types at 0x7f7db1e93d60\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [db_res.c:134]: db_free_result(): freeing result set at 0x7f7db1e94d90\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=663 a=63 n=assign\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=680 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=664 a=25 n=lookup\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: registrar [lookup.c:196]: lookup_helper(): contact for [+3481818181] found by address\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=680 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=681 a=39 n=setflag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=684 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=533 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=530 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=532 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=531 a=25 n=t_is_set\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=531 a=25 n=t_on_branch\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=533 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=535 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=t_is_set\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=534 a=25 n=t_on_reply\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=536 a=25 n=is_method\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=538 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=t_is_set\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=537 a=25 n=t_on_failure\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=543 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=24 n=t_relay\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=3 , global msg id=3 , T on entrance=(nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=17941, isACK=0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 1, id 0 entered\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [md5utils.c:67]: MD5StringArray(): MD5 calculated: 0db5f769d2a6f8e22f43cd18dc188e56\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_funcs.c:315]: t_relay_to(): SER: new INVITE\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_reply.c:647]: _reply_light(): DEBUG: reply sent out. buf=0x7f7db1e94ec0: SIP/2.0 100 trying -..., shmem=0x7f7da8021f08: SIP/2.0 100 trying -\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_reply.c:657]: _reply_light(): DEBUG: _reply_light: finished\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=910 a=25 n=xdbg\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003cscript\u003e: new branch [0] to sip:+3481818181@88.5.4.230:9773;ob\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=911 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=777 a=24 n=is_request\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=778 a=24 n=has_totag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: siputils [checks.c:97]: has_totag(): no totag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=787 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=41 n=isflagset\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=25 n=isbflagset\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: tm [t_funcs.c:362]: t_relay_to(): SER: new transaction fwd'ed\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=543 a=2 n=exit\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: message repeated 5 times: [ DEBUG: \u003ccore\u003e [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[797]: DEBUG: \u003ccore\u003e [receive.c:278]: receive_msg(): cleaning up\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:616]: parse_msg(): SIP Reply  (status):\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:618]: parse_msg():  version: \u003cSIP/2.0\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:620]: parse_msg():  status:  \u003c100\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:622]: parse_msg():  reason:  \u003cTrying\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, \u003creceived\u003e = \u003c146.185.142.19\u003e; state=6\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, \u003cbranch\u003e = \u003cz9hG4bK5164.0db5f769d2a6f8e22f43cd18dc188e56.0\u003e; state=16\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [receive.c:134]: receive_msg(): After parse_msg...\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=(nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, \u003crport\u003e = \u003c44637\u003e; state=6\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, \u003creceived\u003e = \u003c46.222.206.146\u003e; state=6\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, \u003cbranch\u003e = \u003cz9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157\u003e; state=16\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: \u003cTo\u003e [34]; uri=[sip:+3481818181@146.185.142.19]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [\u003csip:+3481818181@146.185.142.19\u003e#015#012]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq \u003cCSeq\u003e: \u003c13850\u003e \u003cINVITE\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 17941 label 0 branch 0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f7da801f350)!\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 2, id 0 entered\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 2, reply code 100\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f7da801f350\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=916 a=25 n=xdbg\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003cscript\u003e: incoming reply\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=919 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=918 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=777 a=24 n=is_request\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=787 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=41 n=isflagset\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=25 n=isbflagset\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: exec: *** cfgtrace:onreply_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): -\u003e\u003e\u003e\u003e\u003e\u003e\u003e\u003e\u003e T_code=100, new_code=100\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=-1 icode=0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: message repeated 5 times: [ DEBUG: \u003ccore\u003e [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[796]: DEBUG: \u003ccore\u003e [receive.c:278]: receive_msg(): cleaning up\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:616]: parse_msg(): SIP Reply  (status):\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:618]: parse_msg():  version: \u003cSIP/2.0\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:620]: parse_msg():  status:  \u003c500\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:622]: parse_msg():  reason:  \u003c\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, \u003creceived\u003e = \u003c146.185.142.19\u003e; state=6\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, \u003cbranch\u003e = \u003cz9hG4bK5164.0db5f769d2a6f8e22f43cd18dc188e56.0\u003e; state=16\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [receive.c:134]: receive_msg(): After parse_msg...\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=(nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, \u003crport\u003e = \u003c44637\u003e; state=6\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, \u003creceived\u003e = \u003c46.222.206.146\u003e; state=6\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, \u003cbranch\u003e = \u003cz9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157\u003e; state=16\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=62\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:509]: parse_headers(): parse_headers: this is the second via\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=65e14bd6-04f8-4235-8fb1-5bf452827546\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: \u003cTo\u003e [75]; uri=[sip:+3481818181@146.185.142.19]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [\u003csip:+3481818181@146.185.142.19\u003e]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq \u003cCSeq\u003e: \u003c13850\u003e \u003cINVITE\u003e\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 17941 label 0 branch 0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f7da801f350)!\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 2, id 0 entered\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 2, reply code 500\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f7da801f350\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:2154]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=916 a=25 n=xdbg\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003cscript\u003e: incoming reply\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:onreply_route=[MANAGE_REPLY] c=[/etc/kamailio/kamailio.cfg] l=919 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:1232]: t_should_relay_response(): -\u003e\u003e\u003e\u003e\u003e\u003e\u003e\u003e\u003e T_code=100, new_code=500\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 128, id 0 entered\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 128, reply code 500\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: NOTICE: acc [acc.c:315]: acc_log_request(): ACC: call missed: timestamp=1515761540;method=INVITE;from_tag=42538f71-1d62-45a9-b994-bdb5876d7c3a;to_tag=65e14bd6-04f8-4235-8fb1-5bf452827546;call_id=0ba83c1c-1b50-4903-8f7c-ee602525316e;code=500;reason=;src_user=+34639446862;src_domain=146.185.142.19;src_ip=46.222.206.146;dst_ouser=+3481818181;dst_user=+3481818181;dst_domain=88.5.4.230\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 1\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 2\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 3\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 4\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_extra.h:80]: free_strar_mem(): Freeing memory, type is 2, message_index 6, index i 5\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[MANAGE_FAILURE] c=[/etc/kamailio/kamailio.cfg] l=923 a=5 n=route\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=777 a=24 n=is_request\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=783 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=778 a=24 n=has_totag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: siputils [checks.c:97]: has_totag(): no totag\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=787 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=41 n=isflagset\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=784 a=25 n=isbflagset\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[NATMANAGE] c=[/etc/kamailio/kamailio.cfg] l=785 a=2 n=return\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[MANAGE_FAILURE] c=[/etc/kamailio/kamailio.cfg] l=946 a=16 n=if\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: exec: *** cfgtrace:failure_route=[MANAGE_FAILURE] c=[/etc/kamailio/kamailio.cfg] l=925 a=24 n=t_is_canceled\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T start=0x7f7da801f350\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found!\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:1750]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [msg_translator.c:2229]: generate_res_buf_from_sip_res(): old size: 552, new size: 436\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [msg_translator.c:2246]: generate_res_buf_from_sip_res(): copied size: orig:130, new: 14, rest: 422 msg=#012SIP/2.0 500 #015#012Via: SIP/2.0/UDP 46.222.206.146:44637;rport=44637;received=46.222.206.146;branch=z9hG4bKPjda85db1d-e415-410f-8f7d-d2e4b8ad6157#015#012Record-Route: \u003csip:146.185.142.19:9773;lr\u003e#015#012Call-ID: 0ba83c1c-1b50-4903-8f7c-ee602525316e#015#012From: \u003csip:+34639446862@146.185.142.19\u003e;tag=42538f71-1d62-45a9-b994-bdb5876d7c3a#015#012To: \u003csip:+3481818181@146.185.142.19\u003e;tag=65e14bd6-04f8-4235-8fb1-5bf452827546#015#012CSeq: 13850 INVITE#015#012Content-Length:  0\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f7da801f350, callback type 512, id 0 entered\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: acc [acc_logic.c:643]: tmcb_func(): acc callback called for t(0x7f7da801f350) event type 512, reply code 500\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: tm [t_reply.c:1591]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: message repeated 5 times: [ DEBUG: \u003ccore\u003e [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)]\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)\r\nJan 12 12:52:20 kamailio-alone /usr/sbin/kamailio[795]: DEBUG: \u003ccore\u003e [receive.c:278]: receive_msg(): cleaning up\r\n```\r\n\r\nThe 500 code seems to be sent indeed by client (using pjsip)."}],"action":{"name":"View Issue","url":"https://github.com/kamailio/kamailio/issues/1395#issuecomment-357233382"}}}</script>