[sr-dev] [kamailio/kamailio] kamailio inserts buggy sip header (#1257)

defeden notifications at github.com
Mon Oct 2 18:45:39 CEST 2017 

herein the debug trace:

17(3643) INFO: <script>: New message:
 INVITE sip:edi-2001 at mydomain.com SIP/2.0
Via: SIP/2.0/TCP 192.168.0.162:34348;branch=z9hG4bK.Mm9c9oajI;rport
From: <sip:edi-1 at mydomain.com>;tag=J8Qbs6r5C
To: sip:edi-2001 at 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 at 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 at mydomain.com] 
17(3643) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [sip:edi-2001 at 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 at 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 at mydomain.com>;tag=J8Qbs6r5C
To: sip:edi-2001 at 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 at mydomain.com out_uri=sip:edi-2001 at 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 at 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 at 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 at mydomain.com>;tag=J8Qbs6r5C
To: sip:edi-2001 at mydomain.com
CSeq: 20 INVITE
Call-ID: JRsgN1qwek
Contact: <sip:edi-2001 at 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 at 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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.kamailio.org/pipermail/sr-dev/attachments/20171002/fe33b10d/attachment-0001.html>

More information about the sr-dev mailing list