2 Oct
2017
2 Oct
'17
6:45 p.m.
herein the debug trace:
17(3643) INFO: <script>: New message:
INVITE sip:edi-2001@mydomain.com SIP/2.0
Via: SIP/2.0/TCP 192.168.0.162:34348;branch=z9hG4bK.Mm9c9oajI;rport
From: <sip:edi-1@mydomain.com>;tag=J8Qbs6r5C
To: sip:edi-2001@mydomain.com
CSeq: 20 INVITE
Call-ID: JRsgN1qwek
Max-Forwards: 70
Route: <sip:195.206.195.66:5062;transport=tcp;lr>
Supported: replaces, outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 258
Contact:
<sip:edi-1@218.118.243.20:52470;transport=tcp>;+sip.instance="<urn:uuid:dc62b3bd-0e31-41c1-a688-3f9b3cbbd170>"
User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
v=0
o=edi-1 1263 2377 IN IP4 192.168.0.162
s=Talk
c=IN IP4 192.168.0.162
b=AS:380
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7076 RTP/AVP 0 8 3 101
a=rtpmap:101 telephone-event/8000
a=rtcp-fb:* ccm tmmbr
17(3643) DEBUG: <core> [parser/parse_addr_spec.c:898]: parse_addr_spec(): end of
header reached, state=9
17(3643) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [32]; uri=[sip:edi-2001@mydomain.com]
17(3643) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body
[sip:edi-2001@mydomain.com
]
17(3643) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field:
cseq <CSeq>: <20> <INVITE>
17(3643) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
17(3643) DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=258
17(3643) DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of
header
17(3643) DEBUG: <core> [parser/parse_addr_spec.c:176]: parse_to_param(): DEBUG:
add_param: tag=J8Qbs6r5C
17(3643) DEBUG: <core> [parser/parse_addr_spec.c:898]: parse_addr_spec(): end of
header reached, state=29
17(3643) DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
17(3643) DEBUG: siputils [checks.c:103]: has_totag(): no totag
17(3643) DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global
id=0 T start=0xffffffffffffffff
17(3643) DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start
searching: hash=63038, isACK=0
17(3643) DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching
failed
17(3643) DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no
transaction found
17(3643) DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global
id=1 T end=(nil)
17(3643) DEBUG: outbound [ob_mod.c:496]: use_outbound(): Analysing INVITE for outbound
markers
17(3643) DEBUG: <core> [socket_info.c:583]: grep_sock_info(): grep_sock_info -
checking if host==us: 14==9 && [195.206.195.66] == [127.0.0.1]
17(3643) DEBUG: <core> [socket_info.c:587]: grep_sock_info(): grep_sock_info -
checking if port 5060 (advertise 0) matches port 5062
17(3643) DEBUG: <core> [socket_info.c:583]: grep_sock_info(): grep_sock_info -
checking if host==us: 14==10 && [195.206.195.66] == [10.42.0.11]
17(3643) DEBUG: <core> [socket_info.c:587]: grep_sock_info(): grep_sock_info -
checking if port 5060 (advertise 0) matches port 5062
17(3643) DEBUG: <core> [socket_info.c:583]: grep_sock_info(): grep_sock_info -
checking if host==us: 14==9 && [195.206.195.66] == [127.0.0.1]
17(3643) DEBUG: <core> [socket_info.c:587]: grep_sock_info(): grep_sock_info -
checking if port 5060 (advertise 0) matches port 5062
17(3643) DEBUG: <core> [socket_info.c:583]: grep_sock_info(): grep_sock_info -
checking if host==us: 14==10 && [195.206.195.66] == [10.42.0.11]
17(3643) DEBUG: <core> [socket_info.c:587]: grep_sock_info(): grep_sock_info -
checking if port 5060 (advertise 0) matches port 5062
17(3643) DEBUG: <core> [forward.c:448]: check_self(): check_self: host != me
17(3643) DEBUG: outbound [ob_mod.c:378]: use_outbound_non_reg(): top Route-URI is not me -
outbound not used
17(3643) ERROR: <script>: 3. =============================================>
17(3643) DEBUG: <core> [select.c:429]: run_select(): Calling SELECT 0x7f8fedeae7b8
17(3643) INFO: <script>: if 218.118.243.20 == 10.42.0.70?
17(3643) DEBUG: <core> [select.c:429]: run_select(): Calling SELECT 0x7f8fedeae7b8
17(3643) INFO: <script>: Method (INVITE) ==> from Peer
17(3643) INFO: <script>: Route from peer
17(3643) DEBUG: dispatcher [dispatch.c:1664]: ds_select_dst_limit(): set [1]
17(3643) DEBUG: dispatcher [dispatch.c:1772]: ds_select_dst_limit(): alg hash
[2334507456]
17(3643) DEBUG: dispatcher [dispatch.c:1815]: ds_select_dst_limit(): selected [0-1/0]
<sip:10.42.0.70:5060;transport=TCP>
17(3643) INFO: <script>: Relay message to <sip:edi-2001@mydomain.com> via
<sip:10.42.0.70:5060;transport=TCP>
17(3643) DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=1 , global msg
id=1 , T on entrance=(nil)
17(3643) DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start
searching: hash=63038, isACK=0
17(3643) DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching
failed
17(3643) DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no
transaction found
17(3643) DEBUG: tm [t_hooks.c:380]: run_reqin_callbacks_internal(): DBG:
trans=0x7f8fe11b1a80, callback type 1, id 0 entered
17(3643) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5 calculated:
435a550d1fcde96f6297d49dd19084f2
17(3643) DEBUG: tm [t_funcs.c:347]: t_relay_to(): SER: new INVITE
17(3643) DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize:
resize(0) called
17(3643) DEBUG: <core> [tcp_main.c:2281]: tcpconn_send_put(): tcp_send: send from
reader (3643 (17)), reusing fd
17(3643) DEBUG: <core> [tcp_main.c:2516]: tcpconn_do_send(): tcp_send: sending...
17(3643) DEBUG: <core> [tcp_main.c:2550]: tcpconn_do_send(): tcp_send: after real
write: c= 0x7f8fe119b3e8 n=535 fd=12
17(3643) DEBUG: <core> [tcp_main.c:2551]: tcpconn_do_send(): tcp_send: buf=
SIP/2.0 100 trying -- your call is important to us
Via: SIP/2.0/TCP
192.168.0.162:34348;branch=z9hG4bK.Mm9c9oajI;rport=52470;received=218.118.243.20
From: <sip:edi-1@mydomain.com>;tag=J8Qbs6r5C
To: sip:edi-2001@mydomain.com
CSeq: 20 INVITE
Call-ID: JRsgN1qwek
Server: kamailio (4.2.3 (x86_64/linux))
Content-Length: 0
Warning: 392 10.42.0.11:5060 "Noisy feedback tells: pid=3643
req_src_ip=218.118.243.20 req_src_port=52470 in_uri=sip:edi-2001@mydomain.com
out_uri=sip:edi-2001@mydomain.com via_cnt==1"
17(3643) DEBUG: tm [t_reply.c:728]: _reply_light(): DEBUG: reply sent out.
buf=0x7f8fede9a1d8: SIP/2.0 100 trying -..., shmem=0x7f8fe11b1190: SIP/2.0 100 trying -
17(3643) DEBUG: tm [t_reply.c:738]: _reply_light(): DEBUG: _reply_light: finished
17(3643) INFO: <script>: FIRST LEG======>>>>>>>new branch [0]
to sip:edi-2001@mydomain.com
17(3643) DEBUG: siputils [checks.c:103]: has_totag(): no totag
17(3643) DEBUG: <core> [select.c:429]: run_select(): Calling SELECT 0x7f8fedeae7b8
17(3643) INFO: <script>: if 218.118.243.20 == 10.42.0.70?
17(3643) DEBUG: <core> [select.c:429]: run_select(): Calling SELECT 0x7f8fedeae7b8
17(3643) INFO: <script>: Method (INVITE) ==> from Peer
17(3643) DEBUG: <core> [parser/sdp/sdp_helpr_funcs.c:504]: extract_mediaip():
located IP address [192.168.0.162] in `o=' field
17(3643) DEBUG: <core> [parser/sdp/sdp_helpr_funcs.c:504]: extract_mediaip():
located IP address [192.168.0.162] in `c=' field
17(3643) DEBUG: <core> [parser/sdp/sdp.c:574]: parse_sdp_session(): ignoring unknown
type in a= line: `a=rtcp-fb:* ccm tmmbr
'
17(3643) DEBUG: rtpproxy [rtpproxy_funcs.c:148]: check_content_type(): type
<application/sdp> found valid
17(3643) DEBUG: rtpproxy [rtpproxy.c:2704]: force_rtp_proxy(): proxy reply: 10862
195.206.195.66
17(3643) DEBUG: siputils [checks.c:103]: has_totag(): no totag
17(3643) DEBUG: <core> [msg_translator.c:470]: clen_builder(): clen_builder:
content-length: 279 (279)
17(3643) DEBUG: <core> [msg_translator.c:2893]: create_via_hf(): create_via_hf: id
added: <;i=1>, rcv proto=2
17(3643) DEBUG: <core> [tcp_main.c:2297]: tcpconn_send_put(): tcp_send: tcp
connection found (0x7f8fe11a2938), acquiring fd
17(3643) DEBUG: <core> [tcp_main.c:2307]: tcpconn_send_put(): tcp_send, c=
0x7f8fe11a2938, n=16
18(3644) DEBUG: <core> [tcp_main.c:3565]: handle_ser_child(): handle_ser_child: read
response= 7f8fe11a2938, 2, fd -1 from 17 (3643)
17(3643) DEBUG: <core> [tcp_main.c:2337]: tcpconn_send_put(): tcp_send: after
receive_fd: c= 0x7f8fe11a2938 n=8 fd=13
17(3643) DEBUG: <core> [tcp_main.c:2516]: tcpconn_do_send(): tcp_send: sending...
17(3643) DEBUG: <core> [tcp_main.c:2550]: tcpconn_do_send(): tcp_send: after real
write: c= 0x7f8fe11a2938 n=1169 fd=13
17(3643) DEBUG: <core> [tcp_main.c:2551]: tcpconn_do_send(): tcp_send: buf=
INVITE sip:edi-2001@mydomain.com SIP/2.0
Record-Route: <sip:10.42.0.11;transport=tcp;lr=on;ftag=J8Qbs6r5C;nat=yes>
Via: SIP/2.0/TCP
195.206.195.66:5062;branch=z9hG4bKe36f.435a550d1fcde96f6297d49dd19084f2.0;i=1
Via: SIP/2.0/TCP
192.168.0.162:34348;received=218.118.243.20;branch=z9hG4bK.Mm9c9oajI;rport=52470
From: <sip:edi-1@mydomain.com>;tag=J8Qbs6r5C
To: sip:edi-2001@mydomain.com
CSeq: 20 INVITE
Call-ID: JRsgN1qwek
Contact: <sip:edi-2001@10.42.0.11:5060;transport=TCP>
Max-Forwards: 69
Supported: replaces, outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 279
sip:edi-1@218.118.243.20:52470;transport=tcpUser-Agent: LinphoneAndroid/3.2.7
(belle-sip/1.6.1)
Path:
<sip:195.206.195.66:5062;transport=tcp;lr;received=sip:218.118.243.20:52470%3Btransport%3Dtcp>
v=0
o=edi-1 1263 2377 IN IP4 195.206.195.66
s=Talk
c=IN IP4 195.206.195.66
b=AS:380
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 10862 RTP/AVP 0 8 3 101
a=rtpmap:101 telephone-event/8000
a=rtcp-fb:* ccm tmmbr
a=nortpproxy:yes
--
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/1257#issuecomment-333592693