route doesn't failover

[Leon Zetekoff]

if I dial 411 it fails going to voip.ms with a 486 and refuses to check the other trunks.
if I dial 8005551212 voip sends a 503 and then pbx tries vitelity where it completes.

this looks like a bug

06/02/2018 9:37:02 PM - Currently active calls [none]
06/02/2018 9:36:58 PM - Leg L:17.1[Extn:715] is terminated: Cause: BYE from PBX
06/02/2018 9:36:58 PM - [CM503020]: Call(C:17): Normal call termination. Call originator: Extn:715. Reason: Busy
06/02/2018 9:36:58 PM - L:17.1[Extn:715] failed to reach Line:10000>>411, reason Busy
06/02/2018 9:36:58 PM - Leg L:17.2[Line:10000>>411] is terminated: Cause: 486 Busy Here/INVITE from a.b.c.d:5060
06/02/2018 9:36:58 PM - Call to T:Line:10000>>411@[Dev:sip:[email protected]:5060] from L:17.1[Extn:715] failed, cause: Cause: 486 Busy Here/INVITE from a.b.c.d:5060
06/02/2018 9:36:58 PM - [CM503003]: Call(C:17): Call to <sip:[email protected]:5060> has failed; Cause: 486 Busy Here/INVITE from a.b.c.d:5060
06/02/2018 9:36:58 PM - [CM503025]: Call(C:17): Calling T:Line:10000>>411@[Dev:sip:[email protected]:5060] for L:17.1[Extn:715]
06/02/2018 9:36:58 PM - [CM503027]: Call(C:17): From: Extn:715 ("Shul" <sip:[email protected]:5060>) to T:Line:10002>>411@[Dev:sip:[email protected]:5060]
06/02/2018 9:36:58 PM - [CM503004]: Call(C:17): Route 3: from L:17.1[Extn:715] to T:Line:10002>>411@[Dev:sip:[email protected]:5060]

====================

06/02/2018 9:37:34 PM - [CM503007]: Call(C:18): Extn:715 has joined, contact <sip:[email protected]:27807>
06/02/2018 9:37:34 PM - L:18.3[Line:10001>>8005551212] has joined to L:18.1[Extn:715]
06/02/2018 9:37:33 PM - [CM505003]: Provider:[Vitelity - Outbound] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [] PBX contact: [sip:[email protected]:5060]
06/02/2018 9:37:33 PM - [CM503002]: Call(C:18): Alerting Line:10001>>8005551212 by contact <sip:[email protected]:5060>
06/02/2018 9:37:32 PM - Currently active calls - 1: [18]
06/02/2018 9:37:30 PM - Leg L:18.2[Line:10000>>8005551212] is terminated: Cause: 503 Service Unavailable/INVITE from a.b.c.d:5060
06/02/2018 9:37:30 PM - [CM503025]: Call(C:18): Calling T:Line:10001>>8005551212@[Dev:sip:[email protected]:5060] for L:18.1[Extn:715]
06/02/2018 9:37:30 PM - Call to T:Line:10000>>8005551212@[Dev:sip:[email protected]:5060] from L:18.1[Extn:715] failed, cause: Cause: 503 Service Unavailable/INVITE from a.b.c.d:5060
06/02/2018 9:37:30 PM - [CM503003]: Call(C:18): Call to <sip:[email protected]:5060> has failed; Cause: 503 Service Unavailable/INVITE from e.f.g.h:5060
06/02/2018 9:37:30 PM - [CM503025]: Call(C:18): Calling T:Line:10000>>8005551212@[Dev:sip:[email protected]:5060] for L:18.1[Extn:715]
06/02/2018 9:37:30 PM - [CM503027]: Call(C:18): From: Extn:715 ("Shul" <sip:[email protected]:5060>) to T:Line:10002>>8005551212@[Dev:sip:[email protected]:5060]
06/02/2018 9:37:30 PM - [CM503004]: Call(C:18): Route 3: from L:18.1[Extn:715] to T:Line:10002>>8005551212@[Dev:sip:[email protected]:5060]
06/02/2018 9:37:30 PM - Line limit check: Current # of calls for line Lc:10002(@Alcazar TollFree[<sip:[email protected]:5060>]) is 0; limit is 10
06/02/2018 9:37:30 PM - [CM503027]: Call(C:18): From: Extn:715 ("Shul" <sip:[email protected]:5060>) to T:Line:10001>>8005551212@[Dev:sip:[email protected]:5060]
06/02/2018 9:37:30 PM - [CM503004]: Call(C:18): Route 2: from L:18.1[Extn:715] to T:Line:10001>>8005551212@[Dev:sip:[email protected]:5060]
06/02/2018 9:37:30 PM - Line limit check: Current # of calls for line Lc:10001(@vitelity - Outbound[<sip:[email protected]:5060>]) is 0; limit is 10
06/02/2018 9:37:30 PM - [CM503027]: Call(C:18): From: Extn:715 ("Shul" <sip:[email protected]:5060>) to T:Line:10000>>8005551212@[Dev:sip:[email protected]:5060]
06/02/2018 9:37:30 PM - [CM503004]: Call(C:18): Route 1: from L:18.1[Extn:715] to T:Line:10000>>8005551212@[Dev:sip:[email protected]:5060]
06/02/2018 9:37:30 PM - Line limit check: Current # of calls for line Lc:10000(@Voip.ms[<sip:[email protected]:5060>]) is 0; limit is 10
06/02/2018 9:37:30 PM - Call(C:18): Call from Extn:715 to 8005551212 matches outbound rule 'Default'
06/02/2018 9:37:30 PM - [Flow] Call(C:18): has built target endpoint: Out#:>>Rule{Default}>>8005551212 for call from L:18.1[Extn:715]
06/02/2018 9:37:30 PM - [Flow] Target endpoint for 8005551212 is Out#:>>Rule{Default}>>8005551212
06/02/2018 9:37:30 PM - [CM503010]: Call(C:18): Making route(s) from Extn:715 to <sip:[email protected]:5060>
06/02/2018 9:37:30 PM - [CM505001]: Endpoint Extn:715: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Grandstream WP800 1.0.0.16] PBX contact: [sip:[email protected]:5060;transport=TCP]

 

[leejor]

That is because the call did not fail. Your provider sent a SIP code indicating the line was was busy. If the call was not actually busy, but failed for some reason, then you should ask your provider why they are sending a 486. The outbound rules will not try the next route if a number you reach is busy.

 

[Leon Zetekoff]

Hmmm yeah let me inquire of them thanks