[SR-Users] kamailio does DNS lookup of from host when using TCP

Wed Nov 12 22:10:20 CET 2014

Hello,

apparently the register was replied, then it is another request (could
be another register though).

Can you load debugger module and set tis parameter cfgtrace to 1, redo
the test and send the output?

Also, it would be good to get the sip message for that case -- inside
the request_route, add:

xlog("received message: [[$rb]]\n");

Cheers,
Daniel

On 12/11/14 21:56, Vik Killa wrote:
> Hello,
> I'm wondering why kamailio will try doing a DNS lookup of the from
> host with a REGISTER packet when using TCP?
> It doesn't do this with UDP.
> I always get this error since the host is not a valid domain:
>
> <core> [resolve.c:1726]: sip_hostport2su(): could not resolve
> hostname: "3.local"
>
>
> Here is the full log with debug (error is last line)
>
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize: resize(0) called
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [tcp_main.c:2294]: tcpconn_send_put(): tcp_send: found fd in cache (
> 10, 0x7f860bd18938, 1)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [tcp_main.c:2516]: tcpconn_do_send(): tcp_send: sending...
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [tcp_main.c:2550]: tcpconn_do_send(): tcp_send: after real write: c=
> 0x7f860bd18938 n=639 fd=10
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [tcp_main.c:2551]: tcpconn_do_send(): tcp_send: buf=#012SIP/2.0 401
> Unauthorized#015#012Via: SIP/2.0/TCP
> 192.168.16.120:53972;received=192.168.213.162;rport=53972;branch=z9hG4bKPjac894585917043d28da015d397178725;alias#015#012From:
> "1003"
> <sip:1003 at 3.local>;tag=dbc7a9087cb84006b65d891e5ad1f5ea#015#012To:
> "1003" <sip:1003 at 3.local>;tag=ey7D0K9DX49DF#015#012Call-ID:
> ee72194a1b8e4effa2948e237d804b90#015#012CSeq: 1
> REGISTER#015#012User-Agent: AMPSwitch#015#012Allow: INVITE, ACK, BYE,
> CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY,
> PUBLISH, SUBSCRIBE#015#012Supported: timer, path,
> replaces#015#012WWW-Authenticate: Digest realm="3.local",
> nonce="cdf32dde-ab14-4265-b491-bee703e83255", algorithm=MD5,
> qop="auth"#015#012Content-Length: 0#015#012#015#012
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: tm
> [t_reply.c:1663]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers:
> RETR/FR timers reset
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list:
> destroying list (nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list:
> destroying list (nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list:
> destroying list (nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list:
> destroying list (nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list:
> destroying list (nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list:
> destroying list (nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [xavp.c:448]: xavp_destroy_list(): destroying xavp list (nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [receive.c:298]: receive_msg(): receive_msg: cleaning up
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3026]: DEBUG: <core>
> [io_wait.h:388]: io_watch_add(): DBG: io_watch_add(0xa2c420, 6, 2,
> 0x7f860bd2dbe0), fd_no=1
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235,
> <rport> = <n/a>; state=6
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232,
> <branch> = <z9hG4bKPj19019d19feaa4a06ac1d6cdc31a2e05d>; state=6
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/parse_via.c:1284]: parse_via_param(): Found param type 237,
> <alias> = <n/a>; state=16
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found,
> flags=2
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the
> first via
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [receive.c:154]: receive_msg(): After parse_msg...
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [receive.c:197]: receive_msg(): preparing to run routing scripts...
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: maxfwd
> [mf_funcs.c:85]: is_maxfwd_present(): value = 70 
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/parse_addr_spec.c:898]: parse_addr_spec(): end of header
> reached, state=10
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To>
> [27]; uri=[sip:1003 at 3.local] 
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body ["1003"
> <sip:1003 at 3.local>#015#012]
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq
> <CSeq>: <1> <PUBLISH>
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body :
> content_length=757
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: sanity
> [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: textops
> [textops.c:2478]: has_body_f(): content type is 196613
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: siputils
> [checks.c:103]: has_totag(): no totag
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global
> id=9 T start=0xffffffffffffffff
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start
> searching: hash=12001, isACK=0
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching
> failed
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no
> transaction found
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=10 global
> id=10 T end=(nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=10 , global
> msg id=10 , T on entrance=(nil)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start
> searching: hash=12001, isACK=0
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching
> failed
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm
> [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no
> transaction found
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5 calculated:
> d71ddc300035dfaa15e181fe4d6a9aa7
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [dns_cache.c:566]: _dns_hash_find():
> dns_hash_find(_sip._tcp.3.local(17), 33), h=867
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [resolve.c:770]: get_record(): get_record: lookup(_sip._tcp.3.local,
> 33) failed
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [dns_cache.c:894]: dns_cache_mk_bad_entry():
> dns_cache_mk_bad_entry(_sip._tcp.3.local, 33, 60, 1)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [dns_cache.c:827]: dns_cache_add(): dns_cache_add: adding
> _sip._tcp.3.local(17) 33 (flags=1) at 867
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [dns_cache.c:566]: _dns_hash_find(): dns_hash_find(3.local(7), 1), h=184
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [resolve.c:770]: get_record(): get_record: lookup(3.local, 1) failed
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [dns_cache.c:894]: dns_cache_mk_bad_entry():
> dns_cache_mk_bad_entry(3.local, 1, 60, 1)
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core>
> [dns_cache.c:827]: dns_cache_add(): dns_cache_add: adding 3.local(7) 1
> (flags=1) at 184
> Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: ERROR: <core>
> [resolve.c:1726]: sip_hostport2su(): could not resolve hostname: "3.local"
>
>
>
>
> _______________________________________________
> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
> sr-users at lists.sip-router.org
> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users

-- 
Daniel-Constantin Mierla
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
Kamailio Advanced Training, Nov 24-27, Berlin - http://www.asipto.com

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20141112/ce761b38/attachment.html>