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) calledNov 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=10Nov 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@3.local>;tag=dbc7a9087cb84006b65d891e5ad1f5ea#015#012To: "1003" <sip:1003@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#012Nov 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 resetNov 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 upNov 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=1Nov 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=6Nov 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=6Nov 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=16Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2Nov 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 viaNov 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 = 70Nov 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=10Nov 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@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@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=757Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of headerNov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: textops [textops.c:2478]: has_body_f(): content type is 196613Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: siputils [checks.c:103]: has_totag(): no totagNov 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=0xffffffffffffffffNov 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=0Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failedNov 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 foundNov 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=0Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failedNov 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 foundNov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5 calculated: d71ddc300035dfaa15e181fe4d6a9aa7Nov 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=867Nov 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) failedNov 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 867Nov 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=184Nov 12 15:45:19 ua01-proxy01 /usr/sbin/kamailio[3025]: DEBUG: <core> [resolve.c:770]: get_record(): get_record: lookup(3.local, 1) failedNov 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 184Nov 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@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
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users@lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users