Hi, this is weird.
Since I cvs'ed yesterday, I have'nt been able to recreate the rtx situation.
I will still try to regenerate the fault, but earlier I could just REGISTER, Un-REGISTER a few times and the rtx started.
I'll get back to you, asap.
br hw
On fre, 2007-02-16 at 20:00 +0200, Bogdan-Andrei Iancu wrote:
Hi Helge,
thanks for the log - what is very strange is the timeout values - identical for all retransmission; I applied on CVS more debug logs and also made a small fix in timers (small, but relevant ;) ). Could you please update and give it another try ? again, log are most helpful...
thanks and regards, bogdan
Helge Waastad wrote:
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@openser.org http://openser.org/cgi-bin/mailman/listinfo/users