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

Wed Nov 12 22:18:31 CET 2014

Hi Daniel,
Yes, I will try to get the requested details...

As far as the second REGISTER, this is true.
I am forwarding the REGISTER request to freeswitch, it replies with SIP/2.0
401 Unauthorized
Then the UA sends another REGISTER with the Authorization header.

I hope this makes sense, thanks.
/V

On Wed, Nov 12, 2014 at 4:10 PM, Daniel-Constantin Mierla <miconda at gmail.com
> wrote:

>  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 listsr-users at lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>
>
> --
> Daniel-Constantin Mierlahttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
> Kamailio Advanced Training, Nov 24-27, Berlin - http://www.asipto.com
>
>
> _______________________________________________
> 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
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20141112/3f715cef/attachment.html>