[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