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@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#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@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=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(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users