Hi Helge,
may the fix I did Friday solved the problem - it was a function
parameter which was not updated and truncating happened after ~1.1 hour
of running (it was an "int" instead of "long long" )....
regards,
bogdan
Helge Waastad wrote:
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:[EMAIL PROTECTED]
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
[email protected]
http://openser.org/cgi-bin/mailman/listinfo/users
_______________________________________________
Users mailing list
[email protected]
http://openser.org/cgi-bin/mailman/listinfo/users
