[Users] 1.2.0 and retransmissions
Helge Waastad
helge at smartnet.no
Fri Feb 16 08:32:06 CET 2007
Hi Bogdan,
this is what I see:
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
Dispatcher-02: [LB]:5060 <= 10.46.11.104:5060 REGISTER from:
sip:[id]@[domain]
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
parse_headers: flags=200
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: found end
of header
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
find_first_route: No Route headers found
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
loose_route: There is no Route HF
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
Dispatcher-02: Request from UAC...
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
parse_headers: flags=80
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
Dispatcher-02: UAC behind NAT...
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
parse_headers: flags=800
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
DISPATCHER:ds_select_dst: set index [1->0]
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
DISPATCHER:ds_select_dst: alg hash [4169264799]
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
DISPATCHER:ds_select_dst: selected [0-1/0/0] <sip:[SP]:5080>
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
Dispatcher-02: [LB]:5060 => sip:[SP]:5080 REGISTER
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
parse_headers: flags=ffffffffffffffff
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: DEBUG:
t_newtran: msg id=1018 , global msg id=1017 , T on entrance=0xffffffff
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
parse_headers: flags=ffffffffffffffff
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
parse_headers: flags=78
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
t_lookup_request: start searching: hash=51317, isACK=0
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: DEBUG:
RFC3261 transaction matching failed
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: DEBUG:
t_lookup_request: no transaction found
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: DBG:
trans=0xb60d8368, callback type 1, id 0 entered
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: DEBUG:
mk_proxy: doing DNS lookup...
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
check_via_address(10.46.11.104, 10.46.11.104, 0)
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: DEBUG:
add_to_tail_of_timer[4]: 0xb60d84b4
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
DEBUG:tm:_set_fr_retr: FR_TIMER = 3
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]: DEBUG:
add_to_tail_of_timer[0]: 0xb60d84d0
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
DEBUG:tm:t_relay_to: new transaction fwd'ed
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
DEBUG:tm:UNREF_UNSAFE: after is 0
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
DEBUG:destroy_avp_list: destroying list (nil)
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28921]:
receive_msg: cleaning up
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
timer routine:4,tl=0xb60d84b4 next=(nil), timeout=785159744
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : request resending (t=0xb60d8368,
REGISTER ... )
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
add_to_tail_of_timer[5]: 0xb60d84b4
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : done
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
timer routine:5,tl=0xb60d84b4 next=(nil), timeout=785759744
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : request resending (t=0xb60d8368,
REGISTER ... )
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
add_to_tail_of_timer[6]: 0xb60d84b4
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : done
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
timer routine:6,tl=0xb60d84b4 next=(nil), timeout=786759744
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : request resending (t=0xb60d8368,
REGISTER ... )
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
add_to_tail_of_timer[7]: 0xb60d84b4
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : done
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
timer routine:7,tl=0xb60da30c next=0xb60de19c, timeout=788659744
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
timer routine:7,tl=0xb60de19c next=0xb60d84b4, timeout=788659744
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
timer routine:7,tl=0xb60d84b4 next=(nil), timeout=788759744
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : request resending (t=0xb60d8368,
REGISTER ... )
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
add_to_tail_of_timer[7]: 0xb60d84b4
Feb 16 08:22:31 proxy-02 /usr/sbin/openser-dispatcher[28926]: DEBUG:
retransmission_handler : done
br hw
On tor, 2007-02-15 at 19:05 +0200, Bogdan-Andrei Iancu wrote:
> debug 9 :) ...of the whole transaction...
>
> thanks,
> bogdan
>
> Helge Waastad wrote:
> > Hi,
> > no there are no retransmissions from the UAC.
> >
> > What level do you want the debug on?
> >
> > br hw
> >
> >
> >
> > On tor, 2007-02-15 at 18:29 +0200, Bogdan-Andrei Iancu wrote:
> >
> >> Hi Helge,
> >>
> >> is it a way to reproduce this? is there any retransmission from UAC to
> >> LB? do you have a debug log for this?
> >>
> >> regards,
> >> bogdan
> >>
> >> Helge Waastad wrote:
> >>
> >>> Hi,
> >>> I've started to notice a lot of retransmissions in my 1.2.0 test lab.
> >>>
> >>> I'm running two LB(dispatcher) and two SP's on two servers, using
> >>> separate ports.
> >>>
> >>> What I se is this:
> >>>
> >>> SP:5080 LB:5060 UAC:5060
> >>> | | | <Call><PFrame><Time>
> >>> | | |
> >>> | |<-- REGISTER F1<| 1 PF:1 08:09:21.1442
> >>> | | |
> >>> |<--- REGISTER F2<| | 1 PF:2 08:09:21.1446
> >>> | | |
> >>> |<--- REGISTER F3<| | 1 PF:3 08:09:21.1469
> >>> | | |
> >>> |<--- REGISTER F4<| | 1 PF:4 08:09:21.1469
> >>> | | |
> >>> |<--- REGISTER F5<| | 1 PF:5 08:09:21.1469
> >>> | | |
> >>> |<--- REGISTER F6<| | 1 PF:6 08:09:21.1470
> >>> | | |
> >>> |>F7 100 Trying ->| | 1 PF:7 08:09:21.1615
> >>> | | |
> >>> |>F8 100 Trying ->| | 1 PF:8 08:09:21.1673
> >>> | | |
> >>> |>F9 100 Trying ->| | 1 PF:9 08:09:21.1714
> >>>
> >>>
> >>> dns_failover and blacklists are disabled
> >>>
> >>> Using the same tm timer configurations that I've been using on 1.1.1
> >>> (basically default values)
> >>>
> >>> Can anyone tell me what triggers retransmission here?
> >>>
> >>> (after register, the UAC sends SUBSCRIBE and the same rtx happens)
> >>>
> >>> br hw
> >>>
> >>>
> >>> _______________________________________________
> >>> Users mailing list
> >>> Users at openser.org
> >>> http://openser.org/cgi-bin/mailman/listinfo/users
> >>>
> >>>
> >>>
> >
> >
>
More information about the sr-users
mailing list