[OpenSER-Devel] [ openser-Bugs-1768526 ] next_contacts fails in failure_route on local 408

[SourceForge.net]

Bugs item #1768526, was opened at 2007-08-06 17:14
Message generated for change (Comment added) made by axlh
You can respond by visiting: 
https://sourceforge.net/tracker/?func=detail&atid=743020&aid=1768526&group_id=139143

Please note that this message will contain a full copy of the comment thread,
including the initial issue submission, for this request,
not just the latest update.
Category: None
Group: None
>Status: Closed
>Resolution: Rejected
Priority: 5
Private: No
Submitted By: axlh (axlh)
Assigned to: Nobody/Anonymous (nobody)
Summary: next_contacts fails in failure_route on local 408

Initial Comment:
On a timeout, a locally generated 408 sends the script into the failure_route. When next_contacts is called to get the next destination it fails without a trace although the avp contains another contact.

See below for script and log snippets.

failure_route[1] {
	if (t_check_status("(5..)|(408)")) {
		# Contact failure, try to find next
		TRACE("Host returned 5xx or timeout. (From: $si:$sp)");
		avp_print();
		if not next_contacts() {
			TRACE("No more contacts for <$tu>");
			t_reply("503", "Service not available - No more contacts");
			exit;
		}
		TRACE("Routing via next contact(s). (DSet: '$ds')");
		t_on_failure("1");
		route(2);
		exit;
	}

	TRACE("Relaying normal error from contact.");
	exit;
}




Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: timer routine:0,tl=0xb398e998 next=(nil), timeout=3
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: final_response_handler:stop retr. and send CANCEL (0xb398e830)
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:t_should_relay_response: T_code=100, new_code=408
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:t_pick_branch: picked branch 0, code 408
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:t_should_relay_response: dns-failover test: branch=0, last_recv=408, flags=1
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:t_should_relay_response: trying DNS-based failover
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:t_check_status: checked status is <408>
Aug  6 16:36:59 proxyhost proxy1[25396]: [102 INVITE] <123.123.123.123:5060> Host returned 5xx or timeout. (From: 123.123.123.123:5060)
Aug  6 16:36:59 proxyhost proxy1[25396]: INFO:avpops:print_avp: p=0xb398e7f0, flags=0x0001
Aug  6 16:36:59 proxyhost proxy1[25396]: INFO:			name=<fr_inv_timer_avp>
Aug  6 16:36:59 proxyhost proxy1[25396]: INFO:			val_int=<30>
Aug  6 16:36:59 proxyhost proxy1[25396]: INFO:avpops:print_avp: p=0xb398e7b0, flags=0x0002
Aug  6 16:36:59 proxyhost proxy1[25396]: INFO:			id=<1401>
Aug  6 16:36:59 proxyhost proxy1[25396]: INFO:			val_str=<sip:0987654321 at outproxy2:5065 / 38>
Aug  6 16:36:59 proxyhost proxy1[25396]: [102 INVITE] <123.123.123.123:5060> No more contacts for <sip:0987654321 at proxy1>
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: t_reply_unsafe called from w_t_reply
Aug  6 16:36:59 proxyhost proxy1[25396]: parse_headers: flags=ffffffffffffffff
Aug  6 16:36:59 proxyhost proxy1[25396]: check_via_address(217.114.103.93, 123.123.123.123, 0)
Aug  6 16:36:59 proxyhost proxy1[25396]: DBG: trans=0xb398e830, callback type 128, id 0 entered
Aug  6 16:36:59 proxyhost proxy1[25396]: ACC: call missed: timestamp=1186411019;method=INVITE;from_tag=as2e155305;to_tag=;call_id=6357b6af404a878e3c3221b775de7ce7 at 123.123.123.123;code=503;reason=Service Unavailable; .....
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:set_timer: relative timeout is 500000
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: add_to_tail_of_timer[4]: 0xb398e8f8 (3500000)
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:set_timer: relative timeout is 3
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: add_to_tail_of_timer[0]: 0xb398e914 (6)
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:_reply_light: reply sent out. buf=0x817b0d8: SIP/2.0 5..., shmem=0xb3990458: SIP/2.0 5
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:_reply_light: finished
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:relay_reply: branch=0, save=0, relay=-1
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:set_timer: relative timeout is 500000
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: add_to_tail_of_timer[4]: 0xb398e8f8 (3500000)
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG:tm:set_timer: relative timeout is 3
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: add_to_tail_of_timer[0]: 0xb398e914 (6)
Aug  6 16:36:59 proxyhost proxy1[25396]: DEBUG: final_response_handler : done


----------------------------------------------------------------------

>Comment By: axlh (axlh)
Date: 2007-08-13 15:46

Message:
Logged In: YES 
user_id=1212856
Originator: YES

I'm sorry. This was a bug in a local version which somehow moved itself to
the wrong host.

----------------------------------------------------------------------

You can respond by visiting: 
https://sourceforge.net/tracker/?func=detail&atid=743020&aid=1768526&group_id=139143