11 Jan
2018
11 Jan
'18
5:03 p.m.
### 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
```
--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1395
11 Jan
11 Jan
5:11 p.m.
New subject: [kamailio/kamailio] 500 error on invite response (#1395)
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.
--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1395#issuecomment-356944977
5:20 p.m.
New subject: [kamailio/kamailio] 500 error on invite response (#1395)
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
--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1395#issuecomment-356947343
5:27 p.m.
New subject: [kamailio/kamailio] 500 error on invite response (#1395)
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.
--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1395#issuecomment-356949547
12 Jan
12 Jan
4:05 p.m.
New subject: [kamailio/kamailio] 500 error on invite response (#1395)
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).
--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1395#issuecomment-357233382
15 Jan
15 Jan
5:27 p.m.
New subject: [kamailio/kamailio] 500 error on invite response (#1395)
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…
--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1395#issuecomment-357697453
5:27 p.m.
New subject: [kamailio/kamailio] 500 error on invite response (#1395)
Closed #1395.
--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1395#event-1424435369
2493
days inactive
2497
days old
6 comments
2 participants
participants (2)
-
Daniel-Constantin Mierla -
Pau Giralt