[OpenSIPS-Users] LCR not working

[Saeed Akhtar]

hi all,

I'm setting up LCR in opensips and having a problem. This is the output

Dec 16 15:26:50 [1349] DBG:core:grep_sock_info: checking if host==us: 12==12
&&  [192.168.2.26] == [192.168.2.26]
Dec 16 15:26:50 [1349] DBG:core:grep_sock_info: checking if port 5060
matches port 5060
Dec 16 15:26:50 [1349] DBG:registrar:lookup: '001' Not found in usrloc


Dec 16 15:26:50 [1349] DBG:core:parse_to_param: tag=d7293f4c
Dec 16 15:26:50 [1349] DBG:core:parse_to: end of header reached, state=29
Dec 16 15:26:50 [1349] DBG:core:parse_to: display={"test49"}, ruri={
sip:sipclient1 at 192.168.2.26 <sip%3Asipclient1 at 192.168.2.26>}
Dec 16 15:26:50 [1349] DBG:lcr:do_load_gws: Added matched_gws[0]=[0,0]
Dec 16 15:26:50 [1349] DBG:lcr:do_load_gws: Added gw_uri_avp
<0sip:|0 at 192.168.2.19:5060;transport=udp>

Dec 16 15:26:50 [1349] DBG:tm:t_newtran: transaction on entrance=(nil)
Dec 16 15:26:50 [1349] DBG:core:parse_headers: flags=ffffffffffffffff
Dec 16 15:26:50 [1349] DBG:core:parse_headers: flags=78
Dec 16 15:26:50 [1349] DBG:tm:t_lookup_request: start searching: hash=44209,
isACK=0
Dec 16 15:26:50 [1349] DBG:tm:matching_3261: RFC3261 transaction matching
failed
Dec 16 15:26:50 [1349] DBG:tm:t_lookup_request: no transaction found
Dec 16 15:26:50 [1349] DBG:tm:run_reqin_callbacks: trans=0xb5bfc578,
callback type 1, id 1 entered
Dec 16 15:26:50 [1349] DBG:dialog:build_new_dlg: new dialog 0xb5bfd45c
(c=Zjk0NjFkMTZmNzg0YmEwOWUzZTNjYzhjOTQ5ZjVmYzI.,f=
sip:sipclient1 at 192.168.2.26 <sip%3Asipclient1 at 192.168.2.26>,t=
sip:001 at 192.168.2.26 <sip%3A001 at 192.168.2.26>,ft=d7293f4c) on hash 3194
Dec 16 15:26:50 [1349] DBG:core:parse_headers: flags=ffffffffffffffff
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: current rr is
<sip:192.168.2.26;lr=on;did=a7c.89bac22>
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: current rr is
<sip:192.168.2.26;lr=on;did=a7c.79bac22>
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: current rr is
<sip:192.168.2.26;lr=on;did=a7c.69bac22>
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: current rr is
<sip:192.168.2.26;lr=on;did=a7c.59bac22>
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: current rr is
<sip:192.168.2.26;lr=on;did=a7c.49bac22>
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: current rr is
<sip:192.168.2.26;lr=on;did=a7c.39bac22>
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: current rr is
<sip:192.168.2.26;lr=on;did=a7c.29bac22>
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: skipping 0 route records
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: out rr
[<sip:192.168.2.26;lr=on;did=a7c.89bac22>,<sip:192.168.2.26;lr=on;did=a7c.79bac22>,<sip:192.168.2.26;lr=on;did=a7c.69bac22>,<sip:192.168.2.26;lr=on;did=a7c.59bac22>,<sip:192.168.2.26;lr=on;did=a7c.49bac22>,<sip:192.168.2.26;lr=on;did=a7c.39bac22>,<sip:192.168.2.26;lr=on;did=a7c.29bac22>]
Dec 16 15:26:50 [1349] DBG:core:print_rr_body: we have 7 records
Dec 16 15:26:50 [1349] DBG:dialog:init_leg_info: route_set
<sip:192.168.2.26;lr=on;did=a7c.89bac22>,<sip:192.168.2.26;lr=on;did=a7c.79bac22>,<sip:192.168.2.26;lr=on;did=a7c.69bac22>,<sip:192.168.2.26;lr=on;did=a7c.59bac22>,<sip:192.168.2.26;lr=on;did=a7c.49bac22>,<sip:192.168.2.26;lr=on;did=a7c.39bac22>,<sip:192.168.2.26;lr=on;did=a7c.29bac22>,
contact sip:sipclient1 at 192.168.2.15:5071, cseq 1 and bind_addr udp:
192.168.2.26:5060
Dec 16 15:26:50 [1349] DBG:dialog:dlg_add_leg_info: set leg 0 for
0xb5bfd45c: tag=<d7293f4c> rcseq=<1>
Dec 16 15:26:50 [1349] DBG:dialog:link_dlg: ref dlg 0xb5bfd45c with 3 -> 3
Dec 16 15:26:50 [1349] DBG:rr:add_rr_param: adding (;did=a7c.99bac22)
0x81af828
Dec 16 15:26:50 [1349] DBG:dialog:get_dlg_timeout: invalid AVP value, use
default timeout
Dec 16 15:26:50 [1349] DBG:tm:run_reqin_callbacks: trans=0xb5bfc578,
callback type 1, id 0 entered
Dec 16 15:26:50 [1349] DBG:core:parse_headers: flags=78
Dec 16 15:26:50 [1349] DBG:core:parse_headers: flags=ffffffffffffffff
Dec 16 15:26:50 [1349] DBG:core:check_ip_address: params 192.168.2.26,
192.168.2.26, 0
Dec 16 15:26:50 [1349] DBG:core:_shm_resize: resize(0) called
Dec 16 15:26:50 [1349] DBG:tm:_reply_light: reply sent out. buf=0x81b0e5c:
SIP/2.0 1..., shmem=0xb5bfee3c: SIP/2.0 1
Dec 16 15:26:50 [1349] DBG:tm:_reply_light: finished
new branch at sip:001 at 192.168.2.26 <sip%3A001 at 192.168.2.26>
Dec 16 15:26:50 [1349] DBG:core:mk_proxy: doing DNS lookup...
Dec 16 15:26:50 [1349] DBG:core:check_ip_address: params 192.168.2.26,
192.168.2.26, 0
Dec 16 15:26:50 [1349] DBG:tm:set_timer: relative timeout is 500000
Dec 16 15:26:50 [1349] DBG:tm:insert_timer_unsafe: [4]: 0xb5bfc6c4
(10400000)
Dec 16 15:26:50 [1349] DBG:tm:set_timer: relative timeout is 30
Dec 16 15:26:50 [1349] DBG:tm:insert_timer_unsafe: [0]: 0xb5bfc6e0 (39)
Dec 16 15:26:50 [1349] DBG:tm:t_relay_to: new transaction fwd'ed


and it comes again and again, in infinite loop. Call should be sent to
192.168.2.19 IP but it never does. even wireshark shows nothing.

My configuration is as following

if(!lookup("location","m")) {

    if (!load_gws()) {

           sl_send_reply("503" , "Unable to load gateways");

           exit;

    }

}

route(2);

exit;


>
>
> route [2] {

    if (is_method("INVITE"))

    {

            t_on_branch("2");

            t_on_reply("2");

            t_on_failure("2");

    }

    if (!t_relay())

    {

            sl_reply_error();

    }

    exit;

}



Regards,

Saeed Akhtar
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.opensips.org/pipermail/users/attachments/20091216/d3366312/attachment-0001.htm