Hi,
I see that opensips tries to send the ACK to this address:
Jan 5 17:17:48 [4956] DBG:rr:after_strict: Next hop:
'sip:10.61.20.146;lr=on' is loose router
Also, how do you route the ACK ? do you do it only via "loose_route()" ?
Maybe posting a SIP capture (from opensips box) for the entire call will
help to understand what is going on.
Regards,
Bogdan
fengbin wrote:
Hi,All
I met a problem when I use domain siptest.org <http://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-7...@siptest.org
<mailto:[email protected]>;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:[email protected] <mailto:sip%[email protected]>>
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:[email protected] <mailto:sip%[email protected]>}
Jan 5 17:17:48 [4956] DBG:core:get_hdr_field: <To> [90];
uri=[sip:[email protected] <mailto:sip%[email protected]>]
Jan 5 17:17:48 [4956] DBG:core:get_hdr_field: to body
["terminate"<sip:[email protected]
<mailto:sip%[email protected]>>]
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 <http://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 <http://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 <http://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 <http://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:[email protected] <mailto:sip%[email protected]>}
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-7...@siptest.org
<mailto:[email protected]>;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:[email protected]
<mailto:sip%[email protected]>>;tag=b013e428-ca143d0a-13c4-50029-73fd-2586b295-73fd
To: "terminate"<sip:[email protected]
<mailto:sip%[email protected]>>;tag=b021d428-c8143d0a-13c4-50029-19003-3c15eec5-19003
Call-ID: [email protected]
<mailto:[email protected]>
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:[email protected] <mailto:sip%[email protected]>>
Content-Length: 0
Thanks
--
arithdon
------------------------------------------------------------------------
_______________________________________________
Users mailing list
[email protected]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami, USA
www.voice-system.ro
_______________________________________________
Users mailing list
[email protected]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
