I have an outbound route with the bv trunk listed.
Here a logfile
"TRUNKOUTCID=6177010227") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Set
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/803-09f1ba80", "1?trunkcid") in new stack
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Goto (macro-outbound-callerid,s,12)
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: GotoIf
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/803-09f1ba80", "1|Set|CALLERID(all)=6177010227") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: ExecIf
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Last app: Set|CALLERID(all)=6177010227
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/803-09f1ba80", "0|Set|CALLERID(all)=") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: ExecIf
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/803-09f1ba80", "0|SetCallerPres|prohib_passed_screen") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: ExecIf
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Macro
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/803-09f1ba80", "0|AGI|fixlocalprefix") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: ExecIf
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/803-09f1ba80", "OUTNUM=16177761748") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Set
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/803-09f1ba80", "custom=SIP/BV") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Set
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/803-09f1ba80", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: ExecIf
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/803-09f1ba80", "dialout-trunk-predial-hook|") in new stack
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/803-09f1ba80", "") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Macro
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/803-09f1ba80", "0?bypass|1") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: GotoIf
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/803-09f1ba80", "0?customtrunk") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: GotoIf
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/803-09f1ba80", "SIP/BV/16177761748|300|") in new stack
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Called BV/16177761748
[2009-07-28 16:27:13] NOTICE[2680] chan_sip.c: Failed to authenticate on INVITE to '"6177010227" <sip:
[email protected]>;tag=as22e8b469'
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- SIP/BV-09efec18 is circuit-busy
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Dial
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-dialout-trunk:20] Goto("SIP/803-09f1ba80", "s-CONGESTION|1") in new stack
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Goto
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:1] GotoIf("SIP/803-09f1ba80", "1?noreport") in new stack
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,3)
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: GotoIf
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:3] NoOp("SIP/803-09f1ba80", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
[2009-07-28 16:27:13] DEBUG[4443] app_macro.c: Executed application: Noop
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [16177761748@from-internal:5] Macro("SIP/803-09f1ba80", "outisbusy|") in new stack
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- Executing [s@macro-outisbusy:1] Playback("SIP/803-09f1ba80", "all-circuits-busy-now|noanswer") in new stack
[2009-07-28 16:27:13] VERBOSE[4443] logger.c: -- <SIP/803-09f1ba80> Playing 'all-circuits-busy-now' (language 'en')
[2009-07-28 16:27:15] DEBUG[4443] app_macro.c: Executed application: Playback
[2009-07-28 16:27:15] VERBOSE[4443] logger.c: -- Executing [s@macro-outisbusy:2] Playback("SIP/803-09f1ba80", "pls-try-call-later|noanswer") in new stack
[2009-07-28 16:27:15] VERBOSE[4443] logger.c: -- <SIP/803-09f1ba80> Playing 'pls-try-call-later' (language 'en')
[2009-07-28 16:27:17] DEBUG[4443] app_macro.c: Executed application: Playback
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Executing [s@macro-outisbusy:3] Macro("SIP/803-09f1ba80", "hangupcall") in new stack
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/803-09f1ba80", "vw") in new stack
[2009-07-28 16:27:17] DEBUG[4443] app_macro.c: Executed application: ResetCDR
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/803-09f1ba80", "") in new stack
[2009-07-28 16:27:17] DEBUG[4443] app_macro.c: Executed application: NoCDR
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/803-09f1ba80", "1?skiprg") in new stack
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Goto (macro-hangupcall,s,6)
[2009-07-28 16:27:17] DEBUG[4443] app_macro.c: Executed application: GotoIf
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/803-09f1ba80", "1?skipblkvm") in new stack
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Goto (macro-hangupcall,s,9)
[2009-07-28 16:27:17] DEBUG[4443] app_macro.c: Executed application: GotoIf
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/803-09f1ba80", "1?theend") in new stack
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Goto (macro-hangupcall,s,11)
[2009-07-28 16:27:17] DEBUG[4443] app_macro.c: Executed application: GotoIf
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/803-09f1ba80", "") in new stack
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/803-09f1ba80' in macro 'hangupcall'
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/803-09f1ba80' in macro 'outisbusy'
[2009-07-28 16:27:17] VERBOSE[4443] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/803-09f1ba80'