[OpenSIPS-Users] ACK incorrect handle when use domain
Bogdan-Andrei Iancu
bogdan at voice-system.ro
Wed Jan 5 10:46:29 CET 2011
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-73fd at siptest.org
> <mailto: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 <mailto: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 <mailto: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 <mailto: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
> <mailto: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 <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:c8oqz84zk7z at privacy.org <mailto: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
> <mailto: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
> <mailto:sip%3Ac8oqz84zk7z at privacy.org>>;tag=b013e428-ca143d0a-13c4-50029-73fd-2586b295-73fd
> To: "terminate"<sip:testt_1000 at siptest.org
> <mailto: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
> <mailto: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 <mailto:sip%3Atesto_0 at siptest.org>>
> Content-Length: 0
>
>
>
> Thanks
>
>
> --
> arithdon
> ------------------------------------------------------------------------
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> 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
More information about the Users
mailing list