Freepbx 2.11 not failing over

I have a rate cap set on my provider anveodirect. If that limit is reached I am returned a 503 error. Freepbx does not select the second trunk in the outbound route, even though the anveo trunk has the check mark to continue to next trunk in any failure.

[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] Macro(“SIP/701-000008de”, “user-callerid,LIMIT”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] Set(“SIP/701-000008de”, “TOUCH_MONITOR=1379132053.2406”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] Set(“SIP/701-000008de”, “AMPUSER=701”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/701-000008de”, “0?report”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/701-000008de”, “1?Set(REALCALLERIDNUM=701)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:5] Set(“SIP/701-000008de”, “AMPUSER=701”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:6] Set(“SIP/701-000008de”, “AMPUSERCIDNAME=Ryan-Office”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/701-000008de”, “0?report”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:8] Set(“SIP/701-000008de”, “AMPUSERCID=314594xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:9] Set(“SIP/701-000008de”, “__DIAL_OPTIONS=Ttr”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:10] Set(“SIP/701-000008de”, “CALLERID(all)=“Ryan-Office” <314594xxxx>”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/701-000008de”, “0?limit”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/701-000008de”, “1?Set(GROUP(concurrency_limit)=701)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/701-000008de”, “7?sub-ccss,s,1(from-internal,636395xxxx)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/701-000008de”, “0?Return()”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] Set(“SIP/701-000008de”, “CCSS_SETUP=TRUE”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/701-000008de”, “0?monitor_config,1(from-internal,636395xxxx):monitor_default,1(from-internal,636395xxxx)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/701-000008de”, “0?is_exten”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] StackPop(“SIP/701-000008de”, “”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] Return(“SIP/701-000008de”, “FALSE”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/701-000008de”, “1?continue”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (macro-user-callerid,s,27)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:27] Set(“SIP/701-000008de”, “CALLERID(number)=314594xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:28] Set(“SIP/701-000008de”, “CALLERID(name)=Ryan-Office”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:29] Set(“SIP/701-000008de”, “CDR(cnum)=314594xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:30] Set(“SIP/701-000008de”, “CDR(cnam)=Ryan-Office”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:31] Set(“SIP/701-000008de”, “CHANNEL(language)=en”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] Set(“SIP/701-000008de”, “ROUTEUSER=701”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/701-000008de”, “1?outbound-2-4-5-6-7,636395xxxx,2:outbound-allroutes,636395xxxx,2”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (outbound-2-4-5-6-7,636395xxxx,2)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] Set(“SIP/701-000008de”, “ROUTE_CIDSAVE=“Ryan-Office” <314594xxxx>”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] Set(“SIP/701-000008de”, “MOHCLASS=default”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/701-000008de”, “0?Set(TRUNKCIDOVERRIDE=314594xxxx)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:5] Set(“SIP/701-000008de”, “_NODEST=”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:6] Gosub(“SIP/701-000008de”, “sub-record-check,s,1(out,636395xxxx,)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] Set(“SIP/701-000008de”, “REC_POLICY_MODE_SAVE=”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/701-000008de”, “1?check”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (sub-record-check,s,7)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:7] Set(“SIP/701-000008de”, “__MON_FMT=wav”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/701-000008de”, “1?next”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (sub-record-check,s,11)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/701-000008de”, “0?Return()”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/701-000008de”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/701-000008de”, “0?out,1”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:14] Set(“SIP/701-000008de”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:15] Set(“SIP/701-000008de”, “NOW=1379132053”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:16] Set(“SIP/701-000008de”, “__DAY=13”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:17] Set(“SIP/701-000008de”, “__MONTH=09”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:18] Set(“SIP/701-000008de”, “__YEAR=2013”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:19] Set(“SIP/701-000008de”, “__TIMESTR=20130913-231413”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:20] Set(“SIP/701-000008de”, “__FROMEXTEN=701”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:21] Set(“SIP/701-000008de”, “__CALLFILENAME=out-636395xxxx-701-20130913-231413-1379132053.2406”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:22] Goto(“SIP/701-000008de”, “out,1”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (sub-record-check,out,1)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/701-000008de”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/701-000008de”, “0?record,1(exten,636395xxxx,701)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] Return(“SIP/701-000008de”, “”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:7] Macro(“SIP/701-000008de”, “dialout-trunk,2,1636395xxxx,on”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] Set(“SIP/701-000008de”, “DIAL_TRUNK=2”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] GosubIf(“SIP/701-000008de”, “0?sub-pincheck,s,1()”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/701-000008de”, “0?disabletrunk,1”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:4] Set(“SIP/701-000008de”, “DIAL_NUMBER=1636395xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:5] Set(“SIP/701-000008de”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:6] Set(“SIP/701-000008de”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/701-000008de”, “1?nomax”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/701-000008de”, “0?skipoutcid”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:10] Set(“SIP/701-000008de”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:11] Macro(“SIP/701-000008de”, “outbound-callerid,2”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/701-000008de”, “0?Set(CALLERPRES()=)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/701-000008de”, “0?Set(REALCALLERIDNUM=314594xxxx)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/701-000008de”, “1?normcid”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:6] Set(“SIP/701-000008de”, “USEROUTCID=314594xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:7] Set(“SIP/701-000008de”, “EMERGENCYCID=314594xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:8] Set(“SIP/701-000008de”, “TRUNKOUTCID=”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/701-000008de”, “1?trunkcid”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:14] ExecIf(“SIP/701-000008de”, “0?Set(CALLERID(all)=)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/701-000008de”, “1?Set(CALLERID(all)=314594xxxx)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/701-000008de”, “0?Set(CALLERID(all)=)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:17] ExecIf(“SIP/701-000008de”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:18] Set(“SIP/701-000008de”, “CDR(outbound_cnum)=314594xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:19] Set(“SIP/701-000008de”, “CDR(outbound_cnam)=”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:12] GosubIf(“SIP/701-000008de”, “0?sub-flp-2,s,1()”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:13] Set(“SIP/701-000008de”, “OUTNUM=0842311636395xxxx”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:14] Set(“SIP/701-000008de”, “custom=SIP/AnveoRyan”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:15] ExecIf(“SIP/701-000008de”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/701-000008de”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:17] Macro(“SIP/701-000008de”, “dialout-trunk-predial-hook,”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] MacroExit(“SIP/701-000008de”, “”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:18] GotoIf(“SIP/701-000008de”, “0?bypass,1”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:19] ExecIf(“SIP/701-000008de”, “1?Set(CONNECTEDLINE(num,i)=1636395xxxx)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:20] ExecIf(“SIP/701-000008de”, “1?Set(CONNECTEDLINE(name,i)=CID:314594xxxx)”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/701-000008de”, “0?customtrunk”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:22] Dial(“SIP/701-000008de”, “SIP/AnveoRyan/0842311636395xxxx,300,Tt”) in new stack
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] netsock2.c: == Using SIP RTP TOS bits 184
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] netsock2.c: == Using SIP RTP CoS mark 5
[2013-09-13 23:14:13] VERBOSE[15205][C-0000044b] app_dial.c: – Called SIP/AnveoRyan/0842311636395xxxx
[2013-09-13 23:14:14] VERBOSE[1578][C-0000044b] chan_sip.c: – Got SIP response 503 “Route not found” back from 50.22.101.14:5060
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] app_dial.c: – SIP/AnveoRyan-000008df is circuit-busy
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:23] NoOp(“SIP/701-000008de”, “Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 34”) in new stack
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:24] GotoIf(“SIP/701-000008de”, “1?continue,1:s-CONGESTION,1”) in new stack
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:1] NoOp(“SIP/701-000008de”, “TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 34 - failing through to other trunks”) in new stack
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] pbx.c: – Executing [[email protected]:2] Set(“SIP/701-000008de”, “CALLERID(number)=701”) in new stack
[2013-09-13 23:14:14] VERBOSE[15205][C-0000044b] pbx.c: – Auto fallthrough, channel ‘SIP/701-000008de’ status is ‘CONGESTION’

I found a temporary solution. Don’t know if I discovered a bug, but it’s working. I posted the answers here. http://pbxinaflash.com/community/index.php?threads/anveo-auto-failover.13403/

If a bug exists then report it here. Don’t go over to PBX in a flash to report a bug. It will never get fixed that way.

http://issues.freepbx.org/browse/FREEPBX

I am unsure how to get a login to report a bug. This seems to be a requirement first. Thanks.

Nevermind. It is just a little difficult to navigate. Thanks I found it.

What version of Asterisk are you running?

The dialplan is operating correctly up until the last line of your trace. Priority 2 of the ‘continue’ extension in that macro is the last instruction so Asterisk should return from the macro and the next instruction in the outbound route executed which should be a new call to the next trunk. Instead you are getting the ‘Auto fall through’ issue which makes me question what Asterisk you are running.

If it is a recent version (like 1.8, 10 or 11) then how are you dialing? Is this a straight call picking up a phone and dialing or is there some complicated follow-me or ring group scenario going to an outside line or something similar to that? If so, and you find that just making the call works but that doesn’t work, please file a ticket in the tracker with details of the call flow so we can have a closer look.

Version 11. I have more details listed here - http://issues.freepbx.org/browse/FREEPBX-6753

I don’t believe I have anything fancy. I have an inbound ring group, I don’t believe that is interfering. It has something to do with regular dialing, extension CID num alias and outbound route callerid pattern matching. That is the best I could find. I changed several things, back and forth until CID num alias and outbound pattern matching made it work like it should.