Re: [sr-dev] [kamailio/kamailio] http_async_client frees pointer twice(Kamailio 5.0.4) (#1340)

Attached

From: Federico Cabiddu notifications@github.com Reply-To: kamailio/kamailio reply@reply.github.com Date: Tuesday, 28 November 2017 at 10:42 To: kamailio/kamailio kamailio@noreply.github.com Cc: arik arik.halperin@gmail.com, Author author@noreply.github.com Subject: Re: [kamailio/kamailio] http_async_client frees pointer twice(Kamailio 5.0.4) (#1340)

Could please provide more logs (possible at debug level) and a description of when this happens? Always? upon a failure? In the http reply route? Also, not related, I see that in $var(re_url) you are missing "http://".

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub, or mute the thread.

Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 46, 4, 0x7fe0ddbbc708), fd_no=15 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 50, 4, 0x7fe0ddbbc794), fd_no=16 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 54, 4, 0x7fe0ddbbc820), fd_no=17 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 58, 4, 0x7fe0ddbbc8ac), fd_no=18 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 62, 4, 0x7fe0ddbbc938), fd_no=19 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 66, 4, 0x7fe0ddbbc9c4), fd_no=20 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 70, 4, 0x7fe0ddbbca50), fd_no=21 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 71, 4, 0x7fe0ddbbcadc), fd_no=22 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 72, 4, 0x7fe0ddbbcb68), fd_no=23 Nov 28 08:46:19 tm-kamailio-ingress kamailio[875]: 26(940) DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa4eb40, 73, 4, 0x7fe0ddbbcbf4), fd_no=24 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:600]: parse_msg(): SIP Request: Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:602]: parse_msg(): method: <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): uri: sip:138.197.183.92:5099 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): version: <SIP/2.0> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjAwS7kvUGVJxZEzvLNzmwJf4AN43bYSnn>; state=16 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:491]: parse_headers(): Via found, flags=2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:493]: parse_headers(): this is the first via Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [27]; uri=[sip:1004@138.197.183.92] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:1004@138.197.183.92 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: ] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <28636> <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/receive.c:186]: receive_msg(): --- received sip message - request - call-id: [Tur2OzFG4u2Gmf.9TTWPx5hjyv6YbDoq] - cseq: [28636 REGISTER] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/receive.c:234]: receive_msg(): preparing to run routing scripts... Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=485 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=619 a=25 n=mf_process_maxfwd_header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: maxfwd [mf_funcs.c:76]: is_maxfwd_present(): value = 70 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=633 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=26 n=sanity_check Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=0 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=G.Lmpz.KMTY-9-Tp7YDRBTxzKBuqxKZZ Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=487 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) ALERT: <script>: PSTN Destination is :<null> Walla!!! Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=489 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=492 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=815 a=55 n=force_rport Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=816 a=25 n=nat_uac_test Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/msg_translator.c:159]: check_via_address(): (82.80.164.63, 10.0.0.28, 0) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=823 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=817 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=818 a=24 n=fix_nated_register Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=824 a=39 n=setflag Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=494 a=25 n=is_present_hf Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=509 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=504 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) ERROR: <script>: 1111 Routing to register 1(895) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=505 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1030 a=25 n=save Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-37f-1] (1 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-37f-1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-37f-2] (2 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-37f-2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: registrar [reply.c:377]: build_contact(): created Contact HF: Contact: sip:1004@82.80.164.63:44927;ob;expires=163;received="sip:82.80.164.63:44927", sip:1004@10.0.0.28:44927;ob;expires=300;received="sip:82.80.164.63:44927" Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: sl [sl.c:282]: send_reply(): reply in stateless mode (sl) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1039 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) ERROR: <script>: 1111 PUSHJOIN 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1040 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1041 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) ERROR: <script>: 1111 locking 1004 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1042 a=25 n=lock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 0 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1043 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) ERROR: <script>: 1111 Getting vars 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1044 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1045 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1046 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) ERROR: <script>: 1111 tU=1004 0 0 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1047 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: htable [ht_var.c:89]: pv_set_ht_cell(): set value for $sht(vtp=>join::1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1048 a=25 n=unlock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 1 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1056 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1051 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) ERROR: <script>: 1111 ts_append location 1004 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1033 a=2 n=exit Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/receive.c:275]: receive_msg(): request-route executed in: 1003 usec Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list 0x7fe0ddbf8330 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: message repeated 4 times: [ 1(895) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 1(895) DEBUG: <core> [core/receive.c:364]: receive_msg(): cleaning up Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:600]: parse_msg(): SIP Request: Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:602]: parse_msg(): method: <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): uri: sip:138.197.183.92:5099 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): version: <SIP/2.0> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjy.9YUHv9K0zq6Qz8AKdFk-r01iE4pNMC>; state=16 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:491]: parse_headers(): Via found, flags=2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:493]: parse_headers(): this is the first via Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [27]; uri=[sip:1004@138.197.183.92] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:1004@138.197.183.92 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: ] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <28637> <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/receive.c:186]: receive_msg(): --- received sip message - request - call-id: [Tur2OzFG4u2Gmf.9TTWPx5hjyv6YbDoq] - cseq: [28637 REGISTER] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/receive.c:234]: receive_msg(): preparing to run routing scripts... Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=485 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=619 a=25 n=mf_process_maxfwd_header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: maxfwd [mf_funcs.c:76]: is_maxfwd_present(): value = 70 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=633 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=26 n=sanity_check Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=0 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=XgTDfqhjtcTotD3jDPFxsO2ppY7S-m8E Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=487 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) ALERT: <script>: PSTN Destination is :<null> Walla!!! Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=489 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=492 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=815 a=55 n=force_rport Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=816 a=25 n=nat_uac_test Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/msg_translator.c:159]: check_via_address(): (82.80.164.63, 10.0.0.28, 0) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=823 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=817 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=818 a=24 n=fix_nated_register Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=824 a=39 n=setflag Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=494 a=25 n=is_present_hf Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=509 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=504 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) ERROR: <script>: 1111 Routing to register 2(896) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=505 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1030 a=25 n=save Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-380-1] (1 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-380-1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-380-2] (2 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-380-2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-380-3] (3 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-380-3 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: registrar [reply.c:377]: build_contact(): created Contact HF: Contact: sip:1004@82.80.164.63:44927;ob;expires=300;received="sip:82.80.164.63:44927" Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: sl [sl.c:282]: send_reply(): reply in stateless mode (sl) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1039 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) ERROR: <script>: 1111 PUSHJOIN 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1040 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1041 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) ERROR: <script>: 1111 locking 1004 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1042 a=25 n=lock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 0 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1043 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) ERROR: <script>: 1111 Getting vars 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1044 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1045 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1046 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) ERROR: <script>: 1111 tU=1004 0 0 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1047 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: htable [ht_var.c:89]: pv_set_ht_cell(): set value for $sht(vtp=>join::1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1048 a=25 n=unlock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 1 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1056 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1051 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) ERROR: <script>: 1111 ts_append location 1004 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1033 a=2 n=exit Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/receive.c:275]: receive_msg(): request-route executed in: 916 usec Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list 0x7fe0ddbf8330 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: message repeated 4 times: [ 2(896) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 2(896) DEBUG: <core> [core/receive.c:364]: receive_msg(): cleaning up Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:600]: parse_msg(): SIP Request: Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:602]: parse_msg(): method: <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): uri: sip:138.197.183.92:5099 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): version: <SIP/2.0> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjxhu8S1NJyydfxy.gwPrfe.Vr10wihm5q>; state=16 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:491]: parse_headers(): Via found, flags=2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:493]: parse_headers(): this is the first via Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [27]; uri=[sip:1004@138.197.183.92] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:1004@138.197.183.92 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: ] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <28638> <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/receive.c:186]: receive_msg(): --- received sip message - request - call-id: [Tur2OzFG4u2Gmf.9TTWPx5hjyv6YbDoq] - cseq: [28638 REGISTER] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/receive.c:234]: receive_msg(): preparing to run routing scripts... Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=485 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=619 a=25 n=mf_process_maxfwd_header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: maxfwd [mf_funcs.c:76]: is_maxfwd_present(): value = 70 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=633 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=26 n=sanity_check Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=0 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=5.O8JiVt-kHYaFKlgIlmNXnLKirX8K1X Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=487 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) ALERT: <script>: PSTN Destination is :<null> Walla!!! Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=489 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=492 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=815 a=55 n=force_rport Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=816 a=25 n=nat_uac_test Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/msg_translator.c:159]: check_via_address(): (82.80.164.63, 10.0.0.28, 0) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=823 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=817 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=818 a=24 n=fix_nated_register Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=824 a=39 n=setflag Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=494 a=25 n=is_present_hf Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=509 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=504 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) ERROR: <script>: 1111 Routing to register 4(898) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=505 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1030 a=25 n=save Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-382-1] (1 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-382-1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-382-2] (2 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-382-2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: sl [sl.c:282]: send_reply(): reply in stateless mode (sl) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1039 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) ERROR: <script>: 1111 PUSHJOIN 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1040 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1041 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) ERROR: <script>: 1111 locking 1004 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1042 a=25 n=lock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 0 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1043 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) ERROR: <script>: 1111 Getting vars 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1044 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1045 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1046 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) ERROR: <script>: 1111 tU=1004 0 0 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1047 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: htable [ht_var.c:89]: pv_set_ht_cell(): set value for $sht(vtp=>join::1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1048 a=25 n=unlock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 1 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1056 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1051 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) ERROR: <script>: 1111 ts_append location 1004 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1033 a=2 n=exit Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/receive.c:275]: receive_msg(): request-route executed in: 699 usec Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list 0x7fe0ddbf8330 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: message repeated 4 times: [ 4(898) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 4(898) DEBUG: <core> [core/receive.c:364]: receive_msg(): cleaning up Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:600]: parse_msg(): SIP Request: Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:602]: parse_msg(): method: <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): uri: sip:138.197.183.92:5099 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): version: <SIP/2.0> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjDWS8IcXcvUNOnJUhXXMw-KGgm2hzFpx6>; state=16 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:491]: parse_headers(): Via found, flags=2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:493]: parse_headers(): this is the first via Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [27]; uri=[sip:1004@138.197.183.92] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:1004@138.197.183.92 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: ] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <64607> <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/receive.c:186]: receive_msg(): --- received sip message - request - call-id: [Cjbi-NxKv7jmg8-AUJMnectUWIUgz7i4] - cseq: [64607 REGISTER] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/receive.c:234]: receive_msg(): preparing to run routing scripts... Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=485 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=619 a=25 n=mf_process_maxfwd_header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: maxfwd [mf_funcs.c:76]: is_maxfwd_present(): value = 70 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=633 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=26 n=sanity_check Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=0 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=I9AB-K-2W1QbfB9xq1AFnCUo2v0TAuEW Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=487 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) ALERT: <script>: PSTN Destination is :<null> Walla!!! Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=489 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=492 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=815 a=55 n=force_rport Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=816 a=25 n=nat_uac_test Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/msg_translator.c:159]: check_via_address(): (82.80.164.63, 10.0.0.28, 0) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=823 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=817 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=818 a=24 n=fix_nated_register Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=824 a=39 n=setflag Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=494 a=25 n=is_present_hf Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=509 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=504 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) ERROR: <script>: 1111 Routing to register 3(897) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=505 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1030 a=25 n=save Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-381-1] (1 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-381-1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-381-2] (2 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-381-2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: registrar [reply.c:377]: build_contact(): created Contact HF: Contact: sip:1004@10.0.0.28:44927;ob;expires=300;received="sip:82.80.164.63:44927" Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: sl [sl.c:282]: send_reply(): reply in stateless mode (sl) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1039 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) ERROR: <script>: 1111 PUSHJOIN 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1040 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1041 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) ERROR: <script>: 1111 locking 1004 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1042 a=25 n=lock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 0 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1043 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) ERROR: <script>: 1111 Getting vars 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1044 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1045 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1046 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) ERROR: <script>: 1111 tU=1004 0 0 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1047 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: htable [ht_var.c:89]: pv_set_ht_cell(): set value for $sht(vtp=>join::1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1048 a=25 n=unlock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 1 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1056 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1051 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) ERROR: <script>: 1111 ts_append location 1004 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1054 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1033 a=2 n=exit Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/receive.c:275]: receive_msg(): request-route executed in: 871 usec Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list 0x7fe0ddbf8330 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: message repeated 4 times: [ 3(897) DEBUG: <core> [core/usr_avp.c:631]: destroy_avp_list(): destroying list (nil)] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 3(897) DEBUG: <core> [core/receive.c:364]: receive_msg(): cleaning up Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:600]: parse_msg(): SIP Request: Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:602]: parse_msg(): method: <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): uri: sip:138.197.183.92:5099 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): version: <SIP/2.0> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjdYeHjHbPlejp7Tx7ZNXGoyerlhQ3Jmqg>; state=16 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:491]: parse_headers(): Via found, flags=2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:493]: parse_headers(): this is the first via Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [27]; uri=[sip:1004@138.197.183.92] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:1004@138.197.183.92 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: ] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <64608> <REGISTER> Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/receive.c:186]: receive_msg(): --- received sip message - request - call-id: [Cjbi-NxKv7jmg8-AUJMnectUWIUgz7i4] - cseq: [64608 REGISTER] Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/receive.c:234]: receive_msg(): preparing to run routing scripts... Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=485 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=619 a=25 n=mf_process_maxfwd_header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: maxfwd [mf_funcs.c:76]: is_maxfwd_present(): value = 70 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=624 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=633 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=26 n=sanity_check Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=0 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=Mxea3NowuFKGt5aiXRFWbC-jZgnJcqDR Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=487 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) ALERT: <script>: PSTN Destination is :<null> Walla!!! Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=489 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=492 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=815 a=55 n=force_rport Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=816 a=25 n=nat_uac_test Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [core/msg_translator.c:159]: check_via_address(): (82.80.164.63, 10.0.0.28, 0) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=823 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=817 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=818 a=24 n=fix_nated_register Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=824 a=39 n=setflag Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=827 a=2 n=return Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=494 a=25 n=is_present_hf Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=509 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=503 a=25 n=is_method Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=504 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) ERROR: <script>: 1111 Routing to register 5(899) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=505 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=16 n=if Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1030 a=25 n=save Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-383-1] (1 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-383-1 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-383-2] (2 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-383-2 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [uloc-5a1d225b-383-3] (3 / 19) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: registrar [save.c:410]: pack_ci(): generated ruid is: uloc-5a1d225b-383-3 Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: registrar [reply.c:377]: build_contact(): created Contact HF: Contact: sip:1004@82.80.164.63:44927;ob;expires=300;received="sip:82.80.164.63:44927" Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: sl [sl.c:282]: send_reply(): reply in stateless mode (sl) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[REGISTER] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1032 a=5 n=route Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1039 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) ERROR: <script>: 1111 PUSHJOIN 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1040 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1041 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) ERROR: <script>: 1111 locking 1004 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1042 a=25 n=lock Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) DEBUG: cfgutils [cfgutils.c:661]: cfg_lock_helper(): cfg_lock mode 0 on 535 (1004) Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1043 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) ERROR: <script>: 1111 Getting vars 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1044 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1045 a=63 n=assign Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) exec: *** cfgtrace:request_route=[PUSHJOIN] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1046 a=26 n=xlog Nov 28 08:46:43 tm-kamailio-ingress kamailio[875]: 5(899) ERROR: <script>: 1111 tU=1004 0 0 5(899) exec: *** cfgtrace:request_route=[PUSH