[OpenSIPS-Users] ACK incorrect handle when use domain

[fengbin]

Hi,All


I met a problem when I use domain siptest.org with opensips.
It seems opensips send ACK to itself rather than forward it to destination.

The following is the output log.

There is no any entity in domain table and alias table.

Does anyone met this problem before?

Any comment is appreciated!


Jan  5 17:17:48 [4954] DBG:tm:update_totag_set: new totag
Jan  5 17:17:48 [4954] DBG:tm:insert_timer_unsafe: [2]: 0xb593725c (10)
Jan  5 17:17:48 [4954] DBG:tm:relay_reply: sent buf=0x81a88d0: SIP/2.0 2...,
shmem=0xb5938e00: SIP/2.0 2
Jan  5 17:17:48 [4954] DBG:tm:run_trans_callbacks: trans=0xb5937214,
callback type 256, id 0 entered
ACC: transaction answered:
timestamp=1294219068;method=INVITE;from_tag=b013e428-ca143d0a-13c4-50029-73fd-2586b295-73fd;to_tag=b021d428-c8143d0a-13c4-50029-19003-3c15eec5-19003;call_id=
afead2d8-ca143d0a-13c4-50029-73fd-85bb387-73fd at siptest.org
;code=200;reason=OK
Jan  5 17:17:48 [4954] DBG:tm:cleanup_uac_timers: RETR/FR timers reset
Jan  5 17:17:48 [4954] DBG:tm:t_unref: UNREF_UNSAFE: after is 0
Jan  5 17:17:48 [4954] DBG:core:destroy_avp_list: destroying list (nil)
Jan  5 17:17:48 [4954] DBG:core:receive_msg: cleaning up
Jan  5 17:17:48 [4956] DBG:core:parse_msg: SIP Request:
Jan  5 17:17:48 [4956] DBG:core:parse_msg:  method:  <ACK>
Jan  5 17:17:48 [4956] DBG:core:parse_msg:  uri:     <
sip:testt_1000 at siptest.org <sip%3Atestt_1000 at siptest.org>>
Jan  5 17:17:48 [4956] DBG:core:parse_msg:  version: <SIP/2.0>
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=2
Jan  5 17:17:48 [4956] DBG:core:parse_to_param:
tag=b021d428-c8143d0a-13c4-50029-19003-3c15eec5-19003
Jan  5 17:17:48 [4956] DBG:core:parse_to: end of header reached, state=29
Jan  5 17:17:48 [4956] DBG:core:parse_to: display={"terminate"}, ruri={
sip:testt_1000 at siptest.org <sip%3Atestt_1000 at siptest.org>}
Jan  5 17:17:48 [4956] DBG:core:get_hdr_field: <To> [90]; uri=[
sip:testt_1000 at siptest.org <sip%3Atestt_1000 at siptest.org>]
Jan  5 17:17:48 [4956] DBG:core:get_hdr_field: to body ["terminate"<
sip:testt_1000 at siptest.org <sip%3Atestt_1000 at siptest.org>>]
Jan  5 17:17:48 [4956] DBG:core:get_hdr_field: cseq <CSeq>: <1> <ACK>
Jan  5 17:17:48 [4956] DBG:core:parse_via_param: found param type 235,
<rport> = <n/a>; state=6
Jan  5 17:17:48 [4956] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK-73fd-1c516cd-683d9483>; state=16
Jan  5 17:17:48 [4956] DBG:core:parse_via: end of header reached, state=5
Jan  5 17:17:48 [4956] DBG:core:parse_headers: via found, flags=2
Jan  5 17:17:48 [4956] DBG:core:parse_headers: this is the first via
Jan  5 17:17:48 [4956] DBG:core:receive_msg: After parse_msg...
Jan  5 17:17:48 [4956] DBG:core:receive_msg: preparing to run routing
scripts...
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=8
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=100
Jan  5 17:17:48 [4956] DBG:maxfwd:is_maxfwd_present: value = 70
Jan  5 17:17:48 [4956] DBG:uri:has_totag: totag found
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=200
Jan  5 17:17:48 [4956] DBG:rr:is_preloaded: is_preloaded: No
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if host==us: 7==9
&&  [siptest.org] == [127.0.0.1]
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if port 5060
matches port 5060
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if host==us: 7==12
&&  [siptest.org] == [10.61.20.146]
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if port 5060
matches port 5060
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if host==us: 7==9
&&  [siptest.org] == [127.0.0.1]
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if port 5060
matches port 5060
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if host==us: 7==12
&&  [siptest.org] == [10.61.20.146]
Jan  5 17:17:48 [4956] DBG:core:grep_sock_info: checking if port 5060
matches port 5060
Jan  5 17:17:48 [4956] DBG:rr:after_strict: Next hop:
'sip:10.61.20.146;lr=on' is loose router
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=ffffffffffffffff
Jan  5 17:17:48 [4956] DBG:core:get_hdr_field: content_length=0
Jan  5 17:17:48 [4956] DBG:core:get_hdr_field: found end of header
Jan  5 17:17:48 [4956] DBG:rr:after_strict: The last route URI:
'sip:10.61.20.146;lr=on'
Jan  5 17:17:48 [4956] DBG:tm:t_newtran: transaction on entrance=0xffffffff
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=ffffffffffffffff
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=78
Jan  5 17:17:48 [4956] DBG:tm:t_lookup_request: start searching: hash=39910,
isACK=1
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=38
Jan  5 17:17:48 [4956] DBG:core:parse_to_param:
tag=b013e428-ca143d0a-13c4-50029-73fd-2586b295-73fd
Jan  5 17:17:48 [4956] DBG:core:parse_to: end of header reached, state=29
Jan  5 17:17:48 [4956] DBG:core:parse_to: display={"Anonymous"}, ruri={
sip:c8oqz84zk7z at privacy.org <sip%3Ac8oqz84zk7z at privacy.org>}
Jan  5 17:17:48 [4956] DBG:tm:t_lookup_request: REF_UNSAFE: after is 1
Jan  5 17:17:48 [4956] DBG:tm:t_lookup_request: e2e proxy ACK found
Jan  5 17:17:48 [4956] DBG:tm:t_newtran: building branch for end2end ACK -
flags=1
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=8
Jan  5 17:17:48 [4956] DBG:tm:unmatched_totag: totag for e2e ACK found: 0
Jan  5 17:17:48 [4956] DBG:tm:run_trans_callbacks: trans=0xb5937214,
callback type 4, id 0 entered
Jan  5 17:17:48 [4956] DBG:core:parse_headers: flags=78
ACC: request acknowledged:
timestamp=1294219068;method=ACK;from_tag=b013e428-ca143d0a-13c4-50029-73fd-2586b295-73fd;to_tag=b021d428-c8143d0a-13c4-50029-19003-3c15eec5-19003;call_id=
afead2d8-ca143d0a-13c4-50029-73fd-85bb387-73fd at siptest.org
;code=200;reason=OK
Jan  5 17:17:48 [4956] DBG:tm:t_relay_to: forwarding ACK
Jan  5 17:17:48 [4956] DBG:core:mk_proxy: doing DNS lookup...
Jan  5 17:17:48 [4956] DBG:core:forward_request: sending:
ACK sip:10.61.20.146;lr=on SIP/2.0
From: "Anonymous"<sip:c8oqz84zk7z at privacy.org<sip%3Ac8oqz84zk7z at privacy.org>
>;tag=b013e428-ca143d0a-13c4-50029-73fd-2586b295-73fd
To: "terminate"<sip:testt_1000 at siptest.org <sip%3Atestt_1000 at siptest.org>
>;tag=b021d428-c8143d0a-13c4-50029-19003-3c15eec5-19003
Call-ID: afead2d8-ca143d0a-13c4-50029-73fd-85bb387-73fd at siptest.org
CSeq: 1 ACK
Via: SIP/2.0/UDP 10.61.20.146;branch=z9hG4bK6eb9.90d7b4e.2
Via: SIP/2.0/UDP 10.61.20.202:5060
;received=10.61.20.202;rport=5060;branch=z9hG4bK-73fd-1c516cd-683d9483
Max-Forwards: 69
Contact: <sip:testo_0 at siptest.org <sip%3Atesto_0 at siptest.org>>
Content-Length: 0



Thanks


-- 
arithdon
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20110105/dee2be0c/attachment-0001.htm>