### Description
In some calls I'm getting a 500 error on invite message reponse without reason message. Some times it works well and others the 500 error arises with the same code and situation.
#### Log Messages
``` Jan 11 13:48:47 kamailio-alone /usr/sbin/kamailio[21953]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Jan 11 13:48:47 kamailio-alone /usr/sbin/kamailio[21953]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Jan 11 13:48:47 kamailio-alone /usr/sbin/kamailio[21953]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <500> Jan 11 13:48:47 kamailio-alone /usr/sbin/kamailio[21953]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <>
DEBUG: <core> [msg_translator.c:2246]: generate_res_buf_from_sip_res(): copied size: orig:130, new: 14, rest: 420 msg=#012SIP/2.0 500 #015#012Via: SIP/2.0/UDP 37.133.221.218:9773;rport=9773;received=37.133.221.218;branch=z9hG4bKPj8d748325-ecc1-40dd-9f19-0edbe38f46a0#015#012Record-Route: sip:146.185.142.19:9773;lr#015#012Call-ID: 36fa4c8e-2e61-4b24-a775-584b2616b889#015#012From: sip:+34639446862@146.185.142.19;tag=179c6cb5-593e-4442-8596-1f40def7b336#015#012To: sip:+34696501510@146.185.142.19;tag=206c2f8e-2b61-4c51-af6b-7332e1830952#015#012CSeq: 9763 INVITE#015#012Content-Length: 0
```
#### SIP Traffic
``` U 2018/01/11 13:55:40.134061 37.133.221.218:9773 -> 146.185.142.19:9773 INVITE sip:+34696501510@146.185.142.19:9773 SIP/2.0. Via: SIP/2.0/UDP 37.133.221.218:9773;rport;branch=z9hG4bKPj042d70a5-e935-4129-90d2-29ba7679fdc3. Max-Forwards: 70. From: sip:+34639446862@146.185.142.19;tag=bc792be2-981c-45b9-9d59-cd46951d60cd. To: sip:+34696501510@146.185.142.19. Contact: sip:+34639446862@37.133.221.218:9773;ob. Call-ID: 0cbfc14f-69bb-4390-887a-ed9f673c58bc. CSeq: 25548 INVITE. Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS. Supported: replaces, 100rel, timer, norefersub. Session-Expires: 1800. Min-SE: 90. Content-Type: application/sdp. Content-Length: 476. . v=0. o=- 3724667739 3724667739 IN IP4 192.168.1.141. s=pjmedia. b=AS:84. t=0 0. a=X-nat:0. m=audio 4000 RTP/AVP 98 97 99 104 3 0 8 9 96. c=IN IP4 192.168.1.141. b=TIAS:64000. a=rtcp:4001 IN IP4 192.168.1.141. a=sendrecv. a=rtpmap:98 speex/16000. a=rtpmap:97 speex/8000. a=rtpmap:99 speex/32000. a=rtpmap:104 iLBC/8000. a=fmtp:104 mode=30. a=rtpmap:3 GSM/8000. a=rtpmap:0 PCMU/8000. a=rtpmap:8 PCMA/8000. a=rtpmap:9 G722/8000. a=rtpmap:96 telephone-event/8000. a=fmtp:96 0-16.
U 2018/01/11 13:55:40.143355 146.185.142.19:9773 -> 37.133.221.218:9773 SIP/2.0 100 trying -- your call is important to us. Via: SIP/2.0/UDP 37.133.221.218:9773;rport=9773;branch=z9hG4bKPj042d70a5-e935-4129-90d2-29ba7679fdc3;received=37.133.221.218. From: sip:+34639446862@146.185.142.19;tag=bc792be2-981c-45b9-9d59-cd46951d60cd. To: sip:+34696501510@146.185.142.19. Call-ID: 0cbfc14f-69bb-4390-887a-ed9f673c58bc. CSeq: 25548 INVITE. Server: kamailio (4.3.4 (x86_64/linux)). Content-Length: 0. .
U 2018/01/11 13:55:40.144092 146.185.142.19:9773 -> 37.133.221.218:1353 INVITE sip:+34696501510@37.133.221.218:1353;ob SIP/2.0. Record-Route: sip:146.185.142.19:9773;lr. Via: SIP/2.0/UDP 146.185.142.19:9773;branch=z9hG4bK6df7.9f8cf260e72edcb1fae300fb37edaa58.0. Via: SIP/2.0/UDP 37.133.221.218:9773;received=37.133.221.218;rport=9773;branch=z9hG4bKPj042d70a5-e935-4129-90d2-29ba7679fdc3. Max-Forwards: 69. From: sip:+34639446862@146.185.142.19;tag=bc792be2-981c-45b9-9d59-cd46951d60cd. To: sip:+34696501510@146.185.142.19. Contact: sip:+34639446862@37.133.221.218:9773;ob. Call-ID: 0cbfc14f-69bb-4390-887a-ed9f673c58bc. CSeq: 25548 INVITE. Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS. Supported: replaces, 100rel, timer, norefersub. Session-Expires: 1800. Min-SE: 90. Content-Type: application/sdp. Content-Length: 476. . v=0. o=- 3724667739 3724667739 IN IP4 192.168.1.141. s=pjmedia. b=AS:84. t=0 0. a=X-nat:0. m=audio 4000 RTP/AVP 98 97 99 104 3 0 8 9 96. c=IN IP4 192.168.1.141. b=TIAS:64000. a=rtcp:4001 IN IP4 192.168.1.141. a=sendrecv. a=rtpmap:98 speex/16000. a=rtpmap:97 speex/8000. a=rtpmap:99 speex/32000. a=rtpmap:104 iLBC/8000. a=fmtp:104 mode=30. a=rtpmap:3 GSM/8000. a=rtpmap:0 PCMU/8000. a=rtpmap:8 PCMA/8000. a=rtpmap:9 G722/8000. a=rtpmap:96 telephone-event/8000. a=fmtp:96 0-16.
U 2018/01/11 13:55:40.459709 37.133.221.218:1353 -> 146.185.142.19:9773 SIP/2.0 100 Trying. Via: SIP/2.0/UDP 146.185.142.19:9773;received=146.185.142.19;branch=z9hG4bK6df7.9f8cf260e72edcb1fae300fb37edaa58.0. Via: SIP/2.0/UDP 37.133.221.218:9773;rport=9773;received=37.133.221.218;branch=z9hG4bKPj042d70a5-e935-4129-90d2-29ba7679fdc3. Record-Route: sip:146.185.142.19:9773;lr. Call-ID: 0cbfc14f-69bb-4390-887a-ed9f673c58bc. From: sip:+34639446862@146.185.142.19;tag=bc792be2-981c-45b9-9d59-cd46951d60cd. To: sip:+34696501510@146.185.142.19. CSeq: 25548 INVITE. Content-Length: 0. .
U 2018/01/11 13:55:40.459844 37.133.221.218:1353 -> 146.185.142.19:9773 SIP/2.0 500 . Via: SIP/2.0/UDP 146.185.142.19:9773;received=146.185.142.19;branch=z9hG4bK6df7.9f8cf260e72edcb1fae300fb37edaa58.0. Via: SIP/2.0/UDP 37.133.221.218:9773;rport=9773;received=37.133.221.218;branch=z9hG4bKPj042d70a5-e935-4129-90d2-29ba7679fdc3. Record-Route: sip:146.185.142.19:9773;lr. Call-ID: 0cbfc14f-69bb-4390-887a-ed9f673c58bc. From: sip:+34639446862@146.185.142.19;tag=bc792be2-981c-45b9-9d59-cd46951d60cd. To: sip:+34696501510@146.185.142.19;tag=fe966b81-31e8-484a-9cce-625f31915529. CSeq: 25548 INVITE. Content-Length: 0. .
U 2018/01/11 13:55:40.463028 146.185.142.19:9773 -> 37.133.221.218:1353 ACK sip:+34696501510@37.133.221.218:1353;ob SIP/2.0. Via: SIP/2.0/UDP 146.185.142.19:9773;branch=z9hG4bK6df7.9f8cf260e72edcb1fae300fb37edaa58.0. Max-Forwards: 69. From: sip:+34639446862@146.185.142.19;tag=bc792be2-981c-45b9-9d59-cd46951d60cd. To: sip:+34696501510@146.185.142.19;tag=fe966b81-31e8-484a-9cce-625f31915529. Call-ID: 0cbfc14f-69bb-4390-887a-ed9f673c58bc. CSeq: 25548 ACK. Content-Length: 0. .
```
### Possible Solutions
<!-- If you found a solution or workaround for the issue, describe it. Ideally, provide a pull request with a fix. -->
### Additional Information
* **Kamailio Version** - output of `kamailio -v`
``` version: kamailio 4.4.6 (x86_64/linux) becbde flags: STATS: Off, USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, SHM_MMAP, PKG_MALLOC, Q_MALLOC, F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, USE_FUTEX, FAST_LOCK-ADAPTIVE_WAIT, USE_DNS_CACHE, USE_DNS_FAILOVER, USE_NAPTR, USE_DST_BLACKLIST, HAVE_RESOLV_RES ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144, MAX_LISTEN 16, MAX_URI_SIZE 1024, BUF_SIZE 65535, DEFAULT PKG_SIZE 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: becbde compiled on 08:18:45 Jul 3 2017 with gcc 5.4.0
```
Do you have any sl_send_reply()/send_reply()/t_reply() in kamailio.cfg that has the second parameter a variable? If yes, that variable might not be set (or set to an empty string)...
Kamailio doesn't send many 500 responses internally in the code, so likely is from kamailio.cfg.
The second parameter of the sl_send_reply() is the default message string for each code. This is my kamailio.cfg file: https://gist.github.com/kelf47/fda4b4e61ceeff8dbf29a9ceff123d4a
Looking more at the debug messages, the SIP response without the reason text is received by Kamailio, not generated by Kamailio ...
Can you check that? Sniff sip traffic on all interfaces in case there is a spiral/loop. Also, maybe you can share more debug messages printed by pid 21953, before those that you pasted in the first post.
this is a more accurate debug trace:
``` 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 ```
The 500 code seems to be sent indeed by client (using pjsip).
Closing, the issue of missing reason text is not in kamailio.
Maybe you can get an workaround in kamailio with:
- https://www.kamailio.org/docs/modules/stable/modules/textopsx.html#textopsx....
Closed #1395.
-
Daniel-Constantin Mierla -
Pau Giralt