Outbound Route Trunk Sequence Problems

Hi,

We have FreePBX setup with a 4 Port Sangoma FXO card with 4 Lines Connected
Incomming Calls and Outgoing calls are working with only 1 hassle.

Routes are as follows.

All inbound Calls from all lines are directed to Ring Group 600

there are 3 outbound routes, international, mobile, and national in that order

international and mobile Routes are configured to use the lines in the following order

Line 2 and then Line 1.

National Routes are configured to use the lines in the following order:
1st: Line 2
2nd: Line 3
3rd: Line 4
4th: Line 1

The 4 Lines are setup with the provider for line hunt.

When an incoming call comes in on Line 1 we are able to call out fine.
when a 2 Calls comes in on Line 1 and then 2, we are unable to make a call out.

Even When the Call on Line 1 is hungup.

If a call comes in Directly on Line 2 we are still unable to make a call out.

Any assistance would be greatky appreciated

Checking the asterisk output shows: (obviously Phone numbers Changed For Privacy)

For the Inbound Call on Line 2:

[2011-08-11 16:44:49] VERBOSE[18371] sig_analog.c: – Starting simple switch on ‘DAHDI/2-1’
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:1] NoOp(“DAHDI/2-1”, "Entering from-dahdi with DID == ") in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:2] Ringing(“DAHDI/2-1”, “”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:3] Set(“DAHDI/2-1”, “DID=s”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:4] NoOp(“DAHDI/2-1”, “DID is now s”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:5] GotoIf(“DAHDI/2-1”, “1?dahdiok:checkzap”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Goto (from-zaptel,s,9)
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:9] NoOp(“DAHDI/2-1”, “Is a DAHDI Channel”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:10] Set(“DAHDI/2-1”, “CHAN=2-1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:11] Set(“DAHDI/2-1”, “CHAN=2”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@from-zaptel:12] Macro(“DAHDI/2-1”, “from-dahdi-2,s,1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-from-dahdi-2:1] NoOp(“DAHDI/2-1”, “Entering macro-from-dahdi-2 with DID = s and setting to: 0812345678”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-from-dahdi-2:2] Set(“DAHDI/2-1”, “__FROM_DID=0812345678”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-from-dahdi-2:3] Goto(“DAHDI/2-1”, “from-trunk,0812345678,1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Goto (from-trunk,0812345678,1)
[2011-08-11 16:44:49] VERBOSE[18371] app_macro.c: == Channel ‘DAHDI/2-1’ jumping out of macro ‘from-dahdi-2’
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [0812345678@from-trunk:1] Set(“DAHDI/2-1”, “__FROM_DID=0812345678”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [0812345678@from-trunk:2] Gosub(“DAHDI/2-1”, “app-blacklist-check,s,1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“DAHDI/2-1”, “0?blacklisted”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@app-blacklist-check:2] Set(“DAHDI/2-1”, “CALLED_BLACKLIST=1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@app-blacklist-check:3] Return(“DAHDI/2-1”, “”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [0812345678@from-trunk:3] ExecIf(“DAHDI/2-1”, “1 ?Set(CALLERID(name)=)”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [0812345678@from-trunk:4] Set(“DAHDI/2-1”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [0812345678@from-trunk:5] Set(“DAHDI/2-1”, “CALLERPRES()=allowed_not_screened”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [0812345678@from-trunk:6] Goto(“DAHDI/2-1”, “ext-group,600,1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Goto (ext-group,600,1)
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:1] Macro(“DAHDI/2-1”, “user-callerid,”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:1] Set(“DAHDI/2-1”, “AMPUSER=”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“DAHDI/2-1”, “0?report”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“DAHDI/2-1”, “1?Set(REALCALLERIDNUM=)”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:4] Set(“DAHDI/2-1”, “AMPUSER=”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:5] Set(“DAHDI/2-1”, “AMPUSERCIDNAME=”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“DAHDI/2-1”, “1?report”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Goto (macro-user-callerid,s,10)
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:10] GotoIf(“DAHDI/2-1”, “0?continue”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:11] Set(“DAHDI/2-1”, “__TTL=64”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“DAHDI/2-1”, “1?continue”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Goto (macro-user-callerid,s,19)
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:19] Set(“DAHDI/2-1”, “CALLERID(number)=”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:20] Set(“DAHDI/2-1”, “CALLERID(name)=”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-user-callerid:21] NoOp(“DAHDI/2-1”, “Using CallerID “” <>”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:2] GotoIf(“DAHDI/2-1”, “1?skipdb”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Goto (ext-group,600,4)
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:4] Set(“DAHDI/2-1”, “__NODEST=”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:5] Set(“DAHDI/2-1”, “__BLKVM_OVERRIDE=BLKVM/600/DAHDI/2-1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:6] Set(“DAHDI/2-1”, “__BLKVM_BASE=600”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:7] Set(“DAHDI/2-1”, “DB(BLKVM/600/DAHDI/2-1)=TRUE”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:8] Set(“DAHDI/2-1”, “RRNODEST=”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:9] Set(“DAHDI/2-1”, “__NODEST=600”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:10] GosubIf(“DAHDI/2-1”, “0?sub-rgsetcid,s,1”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:11] Set(“DAHDI/2-1”, “__ALERT_INFO=http://nohost\;info=alert-external\;x-line-id=0”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:12] Set(“DAHDI/2-1”, “RecordMethod=Group”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:13] Macro(“DAHDI/2-1”, “record-enable,100-120-124-111-105,Group”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-record-enable:1] MacroExit(“DAHDI/2-1”, “”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:14] Set(“DAHDI/2-1”, “RingGroupMethod=ringall”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [600@ext-group:15] Macro(“DAHDI/2-1”, “dial,40,trw,100-120-124-111-105”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Executing [s@macro-dial:1] GotoIf(“DAHDI/2-1”, “1?dial”) in new stack
[2011-08-11 16:44:49] VERBOSE[18371] pbx.c: – Goto (macro-dial,s,3)


For the outbound Call:

[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-user-callerid:19] Set(“SIP/111-00002d38”, “CALLERID(number)=111”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-user-callerid:20] Set(“SIP/111-00002d38”, “CALLERID(name)=EXT (111)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-user-callerid:21] NoOp(“SIP/111-00002d38”, “Using CallerID “EXT (111)” <111>”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [0401234567@from-internal:2] NoOp(“SIP/111-00002d38”, “Calling Out Route: Mobiles”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [0401234567@from-internal:3] Set(“SIP/111-00002d38”, “MOHCLASS=default”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [0401234567@from-internal:4] Set(“SIP/111-00002d38”, “_NODEST=”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [0401234567@from-internal:5] Macro(“SIP/111-00002d38”, “record-enable,111,OUT,”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-record-enable:1] MacroExit(“SIP/111-00002d38”, “”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [0401234567@from-internal:6] Macro(“SIP/111-00002d38”, “dialout-trunk,2,0401234567,”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/111-00002d38”, “DIAL_TRUNK=2”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/111-00002d38”, “0?sub-pincheck,s,1”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/111-00002d38”, “0?disabletrunk,1”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/111-00002d38”, “DIAL_NUMBER=0401234567”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/111-00002d38”, “DIAL_TRUNK_OPTIONS=trw”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/111-00002d38”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/111-00002d38”, “0?nomax”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:8] GotoIf(“SIP/111-00002d38”, “0?chanfull”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/111-00002d38”, “0?skipoutcid”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/111-00002d38”, “DIAL_TRUNK_OPTIONS=TW”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/111-00002d38”, “outbound-callerid,2”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/111-00002d38”, “0?Set(CALLERPRES()=)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/111-00002d38”, “0?Set(REALCALLERIDNUM=111)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/111-00002d38”, “1?normcid”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/111-00002d38”, “USEROUTCID=”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/111-00002d38”, “EMERGENCYCID=”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/111-00002d38”, “TRUNKOUTCID=0812345678”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/111-00002d38”, “1?trunkcid”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“SIP/111-00002d38”, “1?Set(CALLERID(all)=0812345678)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“SIP/111-00002d38”, “0?Set(CALLERID(all)=)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/111-00002d38”, “0?Set(CALLERID(all)=)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/111-00002d38”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/111-00002d38”, “0?sub-flp-2,s,1”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/111-00002d38”, “OUTNUM=0401234567”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/111-00002d38”, “custom=DAHDI/2”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/111-00002d38”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)TW)”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:16] Macro(“SIP/111-00002d38”, “dialout-trunk-predial-hook,”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/111-00002d38”, “”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:17] GotoIf(“SIP/111-00002d38”, “0?bypass,1”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/111-00002d38”, “0?customtrunk”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:19] Dial(“SIP/111-00002d38”, “DAHDI/2/0401234567,300,TW”) in new stack
[2011-08-11 16:45:31] WARNING[18379] app_dial.c: Unable to create channel of type ‘DAHDI’ (cause 17 - User busy)
[2011-08-11 16:45:31] VERBOSE[18379] app_dial.c: == Everyone is busy/congested at this time (1:1/0/0)
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:20] NoOp(“SIP/111-00002d38”, “Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 17”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-dialout-trunk:21] Goto(“SIP/111-00002d38”, “s-BUSY,1”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Goto (macro-dialout-trunk,s-BUSY,1)
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s-BUSY@macro-dialout-trunk:1] NoOp(“SIP/111-00002d38”, “Dial failed due to trunk reporting BUSY - giving up”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s-BUSY@macro-dialout-trunk:2] PlayTones(“SIP/111-00002d38”, “busy”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s-BUSY@macro-dialout-trunk:3] Busy(“SIP/111-00002d38”, “20”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] app_macro.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on ‘SIP/111-00002d38’ in macro ‘dialout-trunk’
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: == Spawn extension (from-internal, 0401234567, 6) exited non-zero on ‘SIP/111-00002d38’
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [h@from-internal:1] Macro(“SIP/111-00002d38”, “hangupcall”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/111-00002d38”, “1?skiprg”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Goto (macro-hangupcall,s,4)
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/111-00002d38”, “1?skipblkvm”) in new stack
[2011-08-11 16:45:31] VERBOSE[18379] pbx.c: – Goto (macro-hangupcall,s,7)