Change External CID Configuration for Follow Me not working

Beta Program Issues
1

I created a fixed value of “17185551212” and regardless of how I dial that extension ie:internal/external the caller id is not overwritten.

2

you may want to provide a bit more detail then that or you are not going to be able to get any help.

It does work, but maybe your specific configuration is not working because it is being overridden elsewhere or limitations with your trunk.

Try providing a trace of the call that is not working, as well as a few details such as other callerid’s that may be set on the specific trunk in question, outbound route, etc. and then someone may be able to isolate why you are getting the results you are of if there is some failure mode where it is not working as it should.

3

I would have provided it, but nowhere else is there any callerid modifications.

To test it, i directed a DID to an extension, created a follow me, and set the fixed caller ID value. The caller ID showing is the inbound call, not the fixed.

Also, the trunk allows any callerId so its not a trunk issue either.

4

Please … if you would like input tracking down the issue (whether a bug or yours) provide something that we can see.

Thank You

5

VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “__FROM_DID=212XXXXXX”) in new stack
VERBOSE[28639] logger.c: – Executing Gosub(“SIP/1168416176-b6f42a70”, “app-blacklist-check|s|1”) in new stack
VERBOSE[28639] logger.c: – Executing LookupBlacklist(“SIP/1168416176-b6f42a70”, “”) in new stack
DEBUG[28639] db.c: Unable to find key ‘1718437XXXX’ in family 'blacklist’
DEBUG[28639] db.c: Unable to find key ‘John Doe’ in family 'blacklist’
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?blacklisted”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “CALLED_BLACKLIST=1”) in new stack
VERBOSE[28639] logger.c: – Executing Return(“SIP/1168416176-b6f42a70”, “”) in new stack
VERBOSE[28639] logger.c: – Executing Gosub(“SIP/1168416176-b6f42a70”, “cidlookup|cidlookup_2|1”) in new stack
DEBUG[28639] pbx.c: Function result is '1718437XXXX’
DEBUG[28639] pbx.c: Function result is 'Jane Doe’
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “CALLERID(name)=Jane Doe”) in new stack
VERBOSE[28639] logger.c: – Executing Return(“SIP/1168416176-b6f42a70”, “”) in new stack
DEBUG[28639] pbx.c: Function result is 'Jane Doe’
DEBUG[28639] pbx.c: Expression result is '0’
DEBUG[28639] pbx.c: Function result is '1718437XXXX’
VERBOSE[28639] logger.c: – Executing ExecIf(“SIP/1168416176-b6f42a70”, “0 |Set|CALLERID(name)=1718437XXXX”) in new

stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “__CALLINGPRES_SV=allowed_not_screened”) in

new stack
VERBOSE[28639] logger.c: – Executing SetCallerPres(“SIP/1168416176-b6f42a70”, “allowed_not_screened”) in new stack
VERBOSE[28639] logger.c: – Executing Goto(“SIP/1168416176-b6f42a70”, “from-did-direct|3004|1”) in new stack
VERBOSE[28639] logger.c: – Goto (from-did-direct,3004,1)
DEBUG[28639] pbx.c: Function result is 'DIRECT’
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?ext-local|3004|1”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
VERBOSE[28639] logger.c: – Executing Macro(“SIP/1168416176-b6f42a70”, “user-callerid|”) in new stack
DEBUG[28639] pbx.c: Expression result is '1’
DEBUG[28639] pbx.c: Function result is '1718437XXXX’
DEBUG[28639] pbx.c: Function result is '1718437XXXX’
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “AMPUSER=1718437XXXX”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?report”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '1’
DEBUG[28639] pbx.c: Function result is '1718437XXXX’
VERBOSE[28639] logger.c: – Executing ExecIf(“SIP/1168416176-b6f42a70”, “1|Set|REALCALLERIDNUM=1718437XXXX”) in

new stack
DEBUG[28639] app_macro.c: Executed application: ExecIf
DEBUG[28639] pbx.c: Expression result is '0’
DEBUG[28639] pbx.c: Function result is '1718437XXXX’
DEBUG[28639] db.c: Unable to find key ‘1718437XXXX/user’ in family 'DEVICE’
DEBUG[28639] func_db.c: DB: DEVICE/1718437XXXX/user not found in database.
DEBUG[28639] pbx.c: Function result is ''
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “AMPUSER=”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] db.c: Unable to find key ‘/cidname’ in family 'AMPUSER’
DEBUG[28639] func_db.c: DB: AMPUSER//cidname not found in database.
DEBUG[28639] pbx.c: Function result is ''
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “AMPUSERCIDNAME=”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?report”) in new stack
VERBOSE[28639] logger.c: – Goto (macro-user-callerid,s,10)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?continue”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '1’
DEBUG[28639] pbx.c: Expression result is '-1’
DEBUG[28639] pbx.c: Function result is '64’
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “__TTL=64”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?continue”) in new stack
VERBOSE[28639] logger.c: – Goto (macro-user-callerid,s,19)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Function result is '“Jane Doe” <1718437XXXX>'
VERBOSE[28639] logger.c: – Executing NoOp(“SIP/1168416176-b6f42a70”, “Using CallerID “Jane Doe” <1718437XXXX>”) in

new stack
DEBUG[28639] app_macro.c: Executed application: Noop
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?skipdb”) in new stack
VERBOSE[28639] logger.c: – Goto (from-did-direct,3004,5)
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “__NODEST=”) in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”,

“__BLKVM_OVERRIDE=BLKVM/3004/SIP/1168416176-b6f42a70”) in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “__BLKVM_BASE=3004”) in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, "DB(BLKVM/3004/SIP/1168416176-b6f42a70)

=TRUE") in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “RRNODEST=”) in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “__NODEST=3004”) in new stack
DEBUG[28639] pbx.c: Function result is '1’
DEBUG[28639] pbx.c: Function result is 'fixed’
DEBUG[28639] pbx.c: Function result is 'fixed’
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GosubIf(“SIP/1168416176-b6f42a70”, “1?sub-fmsetcid|s|1”) in new stack
DEBUG[28639] pbx.c: Function result is 'fixed’
VERBOSE[28639] logger.c: – Executing Goto(“SIP/1168416176-b6f42a70”, “s-fixed|1”) in new stack
VERBOSE[28639] logger.c: – Goto (sub-fmsetcid,s-fixed,1)
DEBUG[28639] pbx.c: Function result is '0’
DEBUG[28639] pbx.c: Expression result is '0’
DEBUG[28639] pbx.c: Function result is '718252XXXX’
VERBOSE[28639] logger.c: – Executing ExecIf(“SIP/1168416176-b6f42a70”, “0|Set|__TRUNKCIDOVERRIDE=718252XXXX”) in

new stack
VERBOSE[28639] logger.c: – Executing Return(“SIP/1168416176-b6f42a70”, “”) in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “RecordMethod=Group”) in new stack
DEBUG[28639] pbx.c: Function result is '3000-1917862XXXX#'
VERBOSE[28639] logger.c: – Executing Macro(“SIP/1168416176-b6f42a70”, “record-enable|3000-1917862XXXX#|Group”) in

new stack
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?check”) in new stack
VERBOSE[28639] logger.c: – Goto (macro-record-enable,s,4)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing ExecIf(“SIP/1168416176-b6f42a70”, “0|MacroExit|”) in new stack
DEBUG[28639] app_macro.c: Executed application: ExecIf
DEBUG[28639] pbx.c: Expression result is '0’
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?Group:OUT”) in new stack
VERBOSE[28639] logger.c: – Goto (macro-record-enable,s,6)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Function result is '2’
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “LOOPCNT=2”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “ITER=1”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Function result is '3000’
DEBUG[28639] pbx.c: Function result is 'out=Adhoc|in=Adhoc’
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “RECSET=“out=Adhoc|in=Adhoc””) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
ERROR[28639] app_cut.c: Usage: Splits a variable’s contents using the specified delimiter
DEBUG[28639] pbx.c: Function result is ''
WARNING[28639] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected TOKEN, expecting $end; Input:
WARNING[28639] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
DEBUG[28639] pbx.c: Expression result is '""'
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “”"?continue") in new stack
VERBOSE[28639] logger.c: – Goto (macro-record-enable,s,14)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '2’
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “ITER=2”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?begin”) in new stack
VERBOSE[28639] logger.c: – Goto (macro-record-enable,s,8)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Function result is '1917862XXXX#'
DEBUG[28639] db.c: Unable to find key ‘1917862XXXX#/recording’ in family 'AMPUSER’
DEBUG[28639] func_db.c: DB: AMPUSER/1917862XXXX#/recording not found in database.
DEBUG[28639] pbx.c: Function result is ''
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “RECSET=”"") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
ERROR[28639] app_cut.c: Usage: Splits a variable’s contents using the specified delimiter
DEBUG[28639] pbx.c: Function result is ''
WARNING[28639] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected TOKEN, expecting $end; Input:
WARNING[28639] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
DEBUG[28639] pbx.c: Expression result is '""'
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “”"?continue") in new stack
VERBOSE[28639] logger.c: – Goto (macro-record-enable,s,14)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '3’
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “ITER=3”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?begin”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?IN”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] db.c: Unable to find key ‘3000-1917862XXXX#/recording’ in family 'AMPUSER’
DEBUG[28639] func_db.c: DB: AMPUSER/3000-1917862XXXX#/recording not found in database.
DEBUG[28639] pbx.c: Function result is ''
ERROR[28639] app_cut.c: Usage: Splits a variable’s contents using the specified delimiter
DEBUG[28639] pbx.c: Function result is ''
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing ExecIf(“SIP/1168416176-b6f42a70”, “1|MacroExit|”) in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “RingGroupMethod=ringallv2”) in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “_FMGRP=3004”) in new stack
DEBUG[28639] pbx.c: Function result is 'ENABLED’
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?doconfirm”) in new stack
VERBOSE[28639] logger.c: – Goto (from-did-direct,3004,19)
DEBUG[28639] pbx.c: Function result is '20’
DEBUG[28639] pbx.c: Function result is '0’
DEBUG[28639] pbx.c: Expression result is '20’
DEBUG[28639] pbx.c: Function result is '3000-1917862XXXX#'
VERBOSE[28639] logger.c: – Executing Macro(“SIP/1168416176-b6f42a70”, "dial-confirm|20|trw|3000-

1917862XXXX#|3004") in new stack
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, "DB(RG/3004/SIP/1168416176-b6f42a70)

=RINGING") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “__UNIQCHAN=SIP/1168416176-b6f42a70”) in new

stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “USE_CONFIRMATION=TRUE”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “RINGGROUP_INDEX=3004”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: – Executing Set(“SIP/1168416176-b6f42a70”, “ARG4=”) in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: – Executing Macro(“SIP/1168416176-b6f42a70”, “dial|20|trw|3000-1917862XXXX#”) in new stack
DEBUG[28639] pbx.c: Expression result is '1’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “1?dial”) in new stack
VERBOSE[28639] logger.c: – Goto (macro-dial,s,3)
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: – Executing AGI(“SIP/1168416176-b6f42a70”, “dialparties.agi”) in new stack
VERBOSE[28639] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
VERBOSE[28639] logger.c: dialparties.agi: Starting New Dialparties.agi
VERBOSE[28639] logger.c: dialparties.agi: Caller ID name is ‘Jane Doe’ number is '1718437XXXX’
VERBOSE[28639] logger.c: dialparties.agi: USE_CONFIRMATION: 'TRUE’
VERBOSE[28639] logger.c: dialparties.agi: RINGGROUP_INDEX: '3004’
VERBOSE[28639] logger.c: dialparties.agi: Methodology of ring is 'ringallv2’
VERBOSE[28639] logger.c: – dialparties.agi: Added extension 3000 to extension map
VERBOSE[28639] logger.c: – dialparties.agi: Added extension 1917862XXXX# to extension map
VERBOSE[28639] logger.c: > dialparties.agi: got fmgrp_prering: 2, fmgrp_grptime: 20
VERBOSE[28639] logger.c: > dialparties.agi: fmgrp_totalprering: 22
VERBOSE[28639] logger.c: > dialparties.agi: extension not in group list, ringing only during prering time
VERBOSE[28639] logger.c: > dialparties.agi: ringallv2 ring times: REALPRERING: 2, PRERING: 2
DEBUG[28639] db.c: Unable to find key ‘3004/screen’ in family 'AMPUSER’
VERBOSE[28639] logger.c: > dialparties.agi: Extension 1917862XXXX# has call screening off
DEBUG[28639] db.c: Unable to find key ‘3004’ in family 'CF’
VERBOSE[28639] logger.c: – dialparties.agi: Extension 3004 cf is disabled
DEBUG[28639] db.c: Unable to find key ‘3000’ in family 'CF’
VERBOSE[28639] logger.c: – dialparties.agi: Extension 3000 cf is disabled
DEBUG[28639] db.c: Unable to find key ‘1917862XXXX#’ in family 'CF’
VERBOSE[28639] logger.c: – dialparties.agi: Extension 1917862XXXX# cf is disabled
DEBUG[28639] db.c: Unable to find key ‘3004’ in family 'DND’
VERBOSE[28639] logger.c: – dialparties.agi: Extension 3004 do not disturb is disabled
DEBUG[28639] db.c: Unable to find key ‘3000’ in family 'DND’
VERBOSE[28639] logger.c: – dialparties.agi: Extension 3000 do not disturb is disabled
DEBUG[28639] db.c: Unable to find key ‘3004’ in family 'CFB’
DEBUG[28639] db.c: Unable to find key ‘3004’ in family 'CFU’
VERBOSE[28639] logger.c: > dialparties.agi: extnum 3004 has: cw: 1; hascfb: 0 [] hascfu: 0 []
VERBOSE[28639] logger.c: – dialparties.agi: dbset CALLTRACE/3004 to 1718437XXXX
DEBUG[28639] db.c: Unable to find key ‘3000’ in family 'CFB’
DEBUG[28639] db.c: Unable to find key ‘3000’ in family 'CFU’
VERBOSE[28639] logger.c: > dialparties.agi: extnum 3000 has: cw: 1; hascfb: 0 [] hascfu: 0 []
VERBOSE[28639] logger.c: – dialparties.agi: dbset CALLTRACE/3000 to 1718437XXXX
DEBUG[28639] db.c: Unable to find key ‘1917862XXXX#’ in family 'CW’
DEBUG[28639] db.c: Unable to find key ‘1917862XXXX#’ in family 'CFB’
DEBUG[28639] db.c: Unable to find key ‘1917862XXXX#’ in family 'CFU’
VERBOSE[28639] logger.c: > dialparties.agi: extnum 1917862XXXX# has: cw: 0; hascfb: 0 [] hascfu: 0 []
VERBOSE[28639] logger.c: > dialparties.agi: Built External dialstring component for 1917862XXXX: Local/RG-3004-

1917862XXXX#@from-internal
VERBOSE[28639] logger.c: – dialparties.agi: Filtered ARG3: 3004-3000-1917862XXXX
VERBOSE[28639] logger.c: dialparties.agi: FMUNIQUE: SIP/1168416176-b6f42a70, FMRERING: 0, FMREALPRERING: 2,

FMGRPTIME: 20
VERBOSE[28639] logger.c: > dialparties.agi: NODEST: 3004 adding M(auto-blkvm) to dialopts: trwM(auto-blkvm)
VERBOSE[28639] logger.c: – AGI Script dialparties.agi completed, returning 0
DEBUG[28639] app_macro.c: Executed application: AGI
VERBOSE[28639] logger.c: – Executing Dial(“SIP/1168416176-b6f42a70”, "Local/FMPR-3004@from-internal&Local/FMGL-

3000-1917862XXXX#@from-internal|22|trwM(auto-blkvm)") in new st
VERBOSE[28639] logger.c: – Called FMPR-3004@from-internal
VERBOSE[28639] logger.c: – Called FMGL-3000-1917862XXXX#@from-internal
VERBOSE[28639] logger.c: – Local/FMGL-3000-1917862XXXX#@from-internal-f083,1 is ringing
VERBOSE[28639] logger.c: – Local/FMGL-3000-1917862XXXX#@from-internal-f083,1 answered SIP/1168416176-b6f42a70
VERBOSE[28639] logger.c: – Executing Set(“Local/FMGL-3000-1917862XXXX#@from-internal-f083,1”, “__MACRO_RESULT=”)

in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: – Executing DBdel(“Local/FMGL-3000-1917862XXXX#@from-internal-f083,1”,

“BLKVM/3004/SIP/1168416176-b6f42a70”) in new stack
VERBOSE[28639] logger.c: – DBdel: family=BLKVM, key=3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key ‘3004/SIP/1168416176-b6f42a70’ in family 'BLKVM’
VERBOSE[28639] logger.c: – DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
DEBUG[28639] app_dial.c: Macro exited with status 0
DEBUG[28639] channel.c: Got clone lock for masquerade on ‘Local/1917862XXXX@from-internal-60fb,1’ at 0x8b0b8e4
DEBUG[28639] channel.c: Putting channel Local/1917862XXXX@from-internal-60fb,1 in 4/4 formats
DEBUG[28639] channel.c: Released clone lock on 'Local/FMGL-3000-1917862XXXX#@from-internal-f083,1’
DEBUG[28639] channel.c: Done Masquerading Local/1917862XXXX@from-internal-60fb,1 (6)
DEBUG[28639] channel.c: Got DTMF on channel (Local/1917862XXXX@from-internal-60fb,1)
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1, sense=2, features=18
DEBUG[28639] res_features.c: Set time limit to 500
DEBUG[28639] channel.c: Nobody there, continuing…
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Timed out for feature!
DEBUG[28639] channel.c: Got DTMF on channel (Local/1917862XXXX@from-internal-60fb,1)
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1, sense=2, features=18
DEBUG[28639] res_features.c: Set time limit to 500
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Timed out for feature!
DEBUG[28639] channel.c: Got DTMF on channel (Local/1917862XXXX@from-internal-60fb,1)
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1, sense=2, features=18
DEBUG[28639] res_features.c: Set time limit to 500
DEBUG[28639] channel.c: Nobody there, continuing…
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Timed out for feature!
DEBUG[28639] channel.c: Got DTMF on channel (Local/1917862XXXX@from-internal-60fb,1)
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1, sense=2, features=18
DEBUG[28639] res_features.c: Set time limit to 500
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Timed out for feature!
DEBUG[28639] channel.c: Didn’t get a frame from channel: Local/1917862XXXX@from-internal-60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] app_dial.c: Exiting with DIALSTATUS=ANSWER.
VERBOSE[28639] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/1168416176-b6f42a70’ in macro 'dial’
VERBOSE[28639] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/1168416176-b6f42a70’ in macro 'dial

-confirm’
VERBOSE[28639] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/1168416176-b6f42a70’
VERBOSE[28639] logger.c: – Executing Macro(“SIP/1168416176-b6f42a70”, “hangupcall”) in new stack
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?skiprg”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: – Executing NoOp(“SIP/1168416176-b6f42a70”, "Cleaning Up Confirmation Flag:

RG/3004/SIP/1168416176-b6f42a70") in new stack
DEBUG[28639] app_macro.c: Executed application: Noop
VERBOSE[28639] logger.c: – Executing DBdel(“SIP/1168416176-b6f42a70”, “RG/3004/SIP/1168416176-b6f42a70”) in new

stack
VERBOSE[28639] logger.c: – DBdel: family=RG, key=3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key ‘3004/SIP/1168416176-b6f42a70’ in family 'RG’
VERBOSE[28639] logger.c: – DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?skipblkvm”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: – Executing NoOp(“SIP/1168416176-b6f42a70”, "Cleaning Up Block VM Flag:

BLKVM/3004/SIP/1168416176-b6f42a70") in new stack
DEBUG[28639] app_macro.c: Executed application: Noop
VERBOSE[28639] logger.c: – Executing DBdel(“SIP/1168416176-b6f42a70”, “BLKVM/3004/SIP/1168416176-b6f42a70”) in

new stack
VERBOSE[28639] logger.c: – DBdel: family=BLKVM, key=3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key ‘3004/SIP/1168416176-b6f42a70’ in family 'BLKVM’
VERBOSE[28639] logger.c: – DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
DEBUG[28639] pbx.c: Expression result is '0’
VERBOSE[28639] logger.c: – Executing GotoIf(“SIP/1168416176-b6f42a70”, “0?theend”) in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: – Executing DBdel(“SIP/1168416176-b6f42a70”, “FM/DND/3004/SIP/1168416176-b6f42a70”) in

new stack
VERBOSE[28639] logger.c: – DBdel: family=FM, key=DND/3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key ‘DND/3004/SIP/1168416176-b6f42a70’ in family 'FM’
VERBOSE[28639] logger.c: – DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
VERBOSE[28639] logger.c: – Executing Hangup(“SIP/1168416176-b6f42a70”, “”) in new stack
VERBOSE[28639] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/1168416176-b6f42a70’ in

macro 'hangupcall’
VERBOSE[28639] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/1168416176-b6f42a70’
DEBUG[28639] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
DEBUG[28639] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr

(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition
DEBUG[28639] chan_sip.c: update_call_counter(1168416176) - decrement call limit counter

6

this does not look complete. After the dialparties.agi I don’t see where the external call to 917862XXXX is being sent out a trunk and that is the only one that would have the CID set, the CID is not set on internal follow-me extensions (maybe that should be clarified if it is confusing).

However, despite apparently missing call log information to see if it is trying to set the CID, this line is suspect:

VERBOSE[28639] logger.c: -- Executing ExecIf("SIP/1168416176-b6f42a70", "0|Set|__TRUNKCIDOVERRIDE=718252XXXX") in

if I am reading that correctly, it thinks that 718252XXXX has non-numeric characters and is thus not setting the override value since only numeric CIDs are allowed here. I am assuming that you edited that log entry to hide the actual number since iirc, I believe we validate and remove any non-digits anyhow when that is set. If not it may be a bug that we are not properly filtering the value but that filter in the dialplan was explicitly added in case other apps are manipulating the astdb variable where this is set.

So … still non-conclusive and I would ask, if you are editing the log file, to just change the fixed value you are sending out to something non-sensitive and then minimize any editing you may feel you need to do to protect sensitive info in the log (like the phone num it is being follow-me-ed to which I understand you probably don’t want in the forums).

7

Using Follow Me module version 2.10.0.5.
In this scenario, I created a custom extension 700. This extension links to an external number, Local/9862230XXXX@from-internal, to dial my cell phone by triggering the appropriate outbound route to allow passing of caller ID. Within the Follow Me settings for this extension, under “Change External CID Configuration”, the mode was changed to “Fixed CID Value”, and a CID was specified 973457XXXX. for the XXXX, the last 4 digits hidden. When 700 is dialed by another internal extension, the extension number rather than the fixed CID shows as the caller ID on my cell phone.
Below is the call log, modified to obscure the numbers only.

[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [700@from-internal:1] GotoIf(“SIP/400-0000000f”, “1?ext-local,700,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (ext-local,700,1)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [700@ext-local:1] Set(“SIP/400-0000000f”, “__RINGTIMER=35”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [700@ext-local:2] Macro(“SIP/400-0000000f”, “exten-vm,novm,700,0,0,0”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/400-0000000f”, “user-callerid,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/400-0000000f”, “AMPUSER=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/400-0000000f”, “0?report”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/400-0000000f”, “1?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/400-0000000f”, “AMPUSER=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/400-0000000f”, “AMPUSERCIDNAME=ShoIP1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/400-0000000f”, “0?report”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/400-0000000f”, “AMPUSERCID=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/400-0000000f”, “CALLERID(all)=“ShoIP1” <400>”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:9] GotoIf(“SIP/400-0000000f”, “0?limit”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:10] ExecIf(“SIP/400-0000000f”, “0?Set(GROUP(concurrency_limit)=400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:11] ExecIf(“SIP/400-0000000f”, “0?Set(CHANNEL(language)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:12] GosubIf(“SIP/400-0000000f”, “0?sub-ccss,s,1(macro-exten-vm,700)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SIP/400-0000000f”, “0?continue”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:14] Set(“SIP/400-0000000f”, “__TTL=64”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“SIP/400-0000000f”, “1?continue”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (macro-user-callerid,s,26)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/400-0000000f”, “CALLERID(number)=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/400-0000000f”, “CALLERID(name)=ShoIP1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/400-0000000f”, “CHANNEL(language)=en”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/400-0000000f”, “RingGroupMethod=none”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/400-0000000f”, “__EXTTOCALL=700”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/400-0000000f”, “__PICKUPMARK=700”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/400-0000000f”, “RT=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:6] Gosub(“SIP/400-0000000f”, “sub-record-check,s,1(exten,700,)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/400-0000000f”, “1?check”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (sub-record-check,s,3)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/400-0000000f”, “MON_FMT=wav”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:4] GotoIf(“SIP/400-0000000f”, “1?next”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (sub-record-check,s,7)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:7] ExecIf(“SIP/400-0000000f”, “0?Return()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“SIP/400-0000000f”, “0?exten,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/400-0000000f”, “__REC_STATUS=INITIALIZED”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:10] ExecIf(“SIP/400-0000000f”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:11] Set(“SIP/400-0000000f”, “NOW=1330264612”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/400-0000000f”, “__DAY=26”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:13] Set(“SIP/400-0000000f”, “__MONTH=02”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/400-0000000f”, “__YEAR=2012”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/400-0000000f”, “__TIMESTR=20120226-085652”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/400-0000000f”, “__FROMEXTEN=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/400-0000000f”, “__CALLFILENAME=exten-700-400-20120226-085652-1330264612.25”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@sub-record-check:18] Goto(“SIP/400-0000000f”, “exten,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (sub-record-check,exten,1)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:1] GotoIf(“SIP/400-0000000f”, “0?callee”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:2] Set(“SIP/400-0000000f”, “__REC_POLICY_MODE=always”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:3] GotoIf(“SIP/400-0000000f”, “0?caller”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:4] GotoIf(“SIP/400-0000000f”, “0?callee”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:5] ExecIf(“SIP/400-0000000f”, “2?Set(CALLER_PRI=10):Set(CALLER_PRI=0)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:6] ExecIf(“SIP/400-0000000f”, “2?Set(CALLEE_PRI=10):Set(CALLEE_PRI=0)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:7] GotoIf(“SIP/400-0000000f”, “1?caller:callee”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (sub-record-check,exten,10)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:10] Set(“SIP/400-0000000f”, “REC_POLICY_MODE=always”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:11] GosubIf(“SIP/400-0000000f”, “1?record,1(exten,700,400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [record@sub-record-check:1] Set(“SIP/400-0000000f”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [record@sub-record-check:2] MixMonitor(“SIP/400-0000000f”, “2012/02/26/exten-700-400-20120226-085652-1330264612.25.wav,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [record@sub-record-check:3] Set(“SIP/400-0000000f”, “__REC_STATUS=RECORDING”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [record@sub-record-check:4] Set(“SIP/400-0000000f”, “CDR(recordingfile)=exten-700-400-20120226-085652-1330264612.25.wav”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [record@sub-record-check:5] Return(“SIP/400-0000000f”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [exten@sub-record-check:12] Return(“SIP/400-0000000f”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:7] GotoIf(“SIP/400-0000000f”, “1?macrodial”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (macro-exten-vm,s,13)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:13] GosubIf(“SIP/400-0000000f”, “0?clrheader,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-exten-vm:14] Macro(“SIP/400-0000000f”, “dial-one,tr,700”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/400-0000000f”, “DEXTEN=700”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/400-0000000f”, “DIALSTATUS_CW=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/400-0000000f”, “0?screen,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/400-0000000f”, “0?cf,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/400-0000000f”, “1?skip1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (macro-dial-one,s,8)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/400-0000000f”, “0?nodial”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/400-0000000f”, “0?continue”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/400-0000000f”, “EXTHASCW=ENABLED”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/400-0000000f”, “0?next1:cwinusebusy”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (macro-dial-one,s,23)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:23] GotoIf(“SIP/400-0000000f”, “1?next3:continue”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Goto (macro-dial-one,s,24)
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:24] ExecIf(“SIP/400-0000000f”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/400-0000000f”, “0?nodial”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/400-0000000f”, “1?dstring,1():dlocal,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/400-0000000f”, “DSTRING=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/400-0000000f”, “DEVICES=700”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/400-0000000f”, “0?Return()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/400-0000000f”, “0?Set(DEVICES=00)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:5] Set(“SIP/400-0000000f”, “LOOPCNT=1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:6] Set(“SIP/400-0000000f”, “ITER=1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:7] Set(“SIP/400-0000000f”, “THISDIAL=Local/9862230XXXX@from-internal”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/400-0000000f”, “1?zap2dahdi,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/400-0000000f”, “0?Return()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/400-0000000f”, “NEWDIAL=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/400-0000000f”, “LOOPCNT2=1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/400-0000000f”, “ITER2=1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/400-0000000f”, “THISPART2=Local/9862230XXXX@from-internal”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/400-0000000f”, “0?Set(THISPART2=DAHDIal/9862230XXXX@from-internal)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/400-0000000f”, “NEWDIAL=Local/9862230XXXX@from-internal&”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/400-0000000f”, “ITER2=2”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/400-0000000f”, “0?begin2”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/400-0000000f”, “THISDIAL=Local/9862230XXXX@from-internal”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/400-0000000f”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:9] Set(“SIP/400-0000000f”, “DSTRING=Local/9862230XXXX@from-internal&”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:10] Set(“SIP/400-0000000f”, “ITER=2”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/400-0000000f”, “0?begin”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“SIP/400-0000000f”, “DSTRING=Local/9862230XXXX@from-internal”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“SIP/400-0000000f”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/400-0000000f”, “0?nodial”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:28] GotoIf(“SIP/400-0000000f”, “0?skiptrace”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:29] GosubIf(“SIP/400-0000000f”, “1?ctset,1():ctclear,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [ctset@macro-dial-one:1] Set(“SIP/400-0000000f”, “DB(CALLTRACE/700)=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [ctset@macro-dial-one:2] Return(“SIP/400-0000000f”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:30] Set(“SIP/400-0000000f”, “D_OPTIONS=tr”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:31] ExecIf(“SIP/400-0000000f”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:32] ExecIf(“SIP/400-0000000f”, “0?SIPAddHeader()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:33] ExecIf(“SIP/400-0000000f”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:34] GosubIf(“SIP/400-0000000f”, “0?qwait,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:35] Set(“SIP/400-0000000f”, “__CWIGNORE=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:36] Set(“SIP/400-0000000f”, “__KEEPCID=TRUE”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:37] GotoIf(“SIP/400-0000000f”, “0?usegoto,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:38] GotoIf(“SIP/400-0000000f”, “0?godial”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:39] Set(“SIP/400-0000000f”, “CONNECTEDLINE(name,i)=ToPSTN1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:40] Set(“SIP/400-0000000f”, “CONNECTEDLINE(num)=700”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:41] Set(“SIP/400-0000000f”, “D_OPTIONS=trI”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] pbx.c: – Executing [s@macro-dial-one:42] Dial(“SIP/400-0000000f”, “Local/9862230XXXX@from-internal,trI”) in new stack
[2012-02-26 08:56:52] VERBOSE[17057] app_dial.c: – Called Local/9862230XXXX@from-internal
[2012-02-26 08:56:52] VERBOSE[17058] app_mixmonitor.c: == Begin MixMonitor Recording SIP/400-0000000f
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [9862230XXXX@from-internal:1] Macro(“Local/9862230XXXX@from-internal-6583;2”, “user-callerid,LIMIT,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:1] Set(“Local/9862230XXXX@from-internal-6583;2”, “AMPUSER=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?report”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “AMPUSER=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:5] Set(“Local/9862230XXXX@from-internal-6583;2”, “AMPUSERCIDNAME=ShoIP1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?report”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:7] Set(“Local/9862230XXXX@from-internal-6583;2”, “AMPUSERCID=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:8] Set(“Local/9862230XXXX@from-internal-6583;2”, “CALLERID(all)=“ShoIP1” <400>”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?limit”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:10] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(GROUP(concurrency_limit)=400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:11] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CHANNEL(language)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:12] GosubIf(“Local/9862230XXXX@from-internal-6583;2”, “0?sub-ccss,s,1(from-internal,)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?continue”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-user-callerid,s,26)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:26] Set(“Local/9862230XXXX@from-internal-6583;2”, “CALLERID(number)=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:27] Set(“Local/9862230XXXX@from-internal-6583;2”, “CALLERID(name)=ShoIP1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-user-callerid:28] Set(“Local/9862230XXXX@from-internal-6583;2”, “CHANNEL(language)=en”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [9862230XXXX@from-internal:2] Set(“Local/9862230XXXX@from-internal-6583;2”, “MOHCLASS=default”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [9862230XXXX@from-internal:3] Set(“Local/9862230XXXX@from-internal-6583;2”, “_NODEST=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [9862230XXXX@from-internal:4] Gosub(“Local/9862230XXXX@from-internal-6583;2”, “sub-record-check,s,1(out,9862230XXXX,)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?check”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (sub-record-check,s,3)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@sub-record-check:3] Set(“Local/9862230XXXX@from-internal-6583;2”, “MON_FMT=wav”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@sub-record-check:4] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?next”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@sub-record-check:5] Set(“Local/9862230XXXX@from-internal-6583;2”, “CDR(recordingfile)=exten-700-400-20120226-085652-1330264612.25.wav”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@sub-record-check:6] Return(“Local/9862230XXXX@from-internal-6583;2”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [9862230XXXX@from-internal:5] Macro(“Local/9862230XXXX@from-internal-6583;2”, “dialout-trunk,8,1862230XXXX,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK=8”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“Local/9862230XXXX@from-internal-6583;2”, “0?sub-pincheck,s,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?disabletrunk,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_NUMBER=1862230XXXX”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“Local/9862230XXXX@from-internal-6583;2”, “OUTBOUND_GROUP=OUT_8”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?nomax”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?skipoutcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“Local/9862230XXXX@from-internal-6583;2”, “outbound-callerid,8”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERPRES()=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?normcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “USEROUTCID=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:5] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?bypass”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“Local/9862230XXXX@from-internal-6583;2”, “USEROUTCID=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“Local/9862230XXXX@from-internal-6583;2”, “EMERGENCYCID=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“Local/9862230XXXX@from-internal-6583;2”, “TRUNKOUTCID=8622466042”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?trunkcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CALLERID(all)=8622466042)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“Local/9862230XXXX@from-internal-6583;2”, “0?sub-flp-8,s,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“Local/9862230XXXX@from-internal-6583;2”, “OUTNUM=1862230XXXX”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“Local/9862230XXXX@from-internal-6583;2”, “custom=SIP/fpbx-1-64804d09”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“Local/9862230XXXX@from-internal-6583;2”, “dialout-trunk-predial-hook,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/9862230XXXX@from-internal-6583;2”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?bypass,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CONNECTEDLINE(num,i)=1862230XXXX)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CONNECTEDLINE(name,i)=CID:8622466042)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?customtrunk”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“Local/9862230XXXX@from-internal-6583;2”, “SIP/fpbx-1-64804d09/1862230XXXX,300,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] netsock2.c: == Using SIP RTP TOS bits 184
[2012-02-26 08:56:52] VERBOSE[17059] netsock2.c: == Using SIP RTP CoS mark 5
[2012-02-26 08:56:52] VERBOSE[17059] app_dial.c: – Called SIP/fpbx-1-64804d09/1862230XXXX
[2012-02-26 08:56:52] VERBOSE[17059] app_dial.c: – SIP/fpbx-1-64804d09-00000010 is circuit-busy
[2012-02-26 08:56:52] VERBOSE[17059] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:23] NoOp(“Local/9862230XXXX@from-internal-6583;2”, “Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:24] Goto(“Local/9862230XXXX@from-internal-6583;2”, “s-CONGESTION,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,s-CONGESTION,1)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-6583;2”, “RC=1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:2] Goto(“Local/9862230XXXX@from-internal-6583;2”, “1,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,1,1)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [1@macro-dialout-trunk:1] Goto(“Local/9862230XXXX@from-internal-6583;2”, “continue,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [continue@macro-dialout-trunk:1] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?noreport”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,continue,3)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [continue@macro-dialout-trunk:3] NoOp(“Local/9862230XXXX@from-internal-6583;2”, “TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 1 - failing through to other trunks”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [continue@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “CALLERID(number)=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [9862230XXXX@from-internal:6] Macro(“Local/9862230XXXX@from-internal-6583;2”, “dialout-trunk,9,1862230XXXX,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK=9”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“Local/9862230XXXX@from-internal-6583;2”, “0?sub-pincheck,s,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?disabletrunk,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_NUMBER=1862230XXXX”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“Local/9862230XXXX@from-internal-6583;2”, “OUTBOUND_GROUP=OUT_9”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?nomax”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?skipoutcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“Local/9862230XXXX@from-internal-6583;2”, “outbound-callerid,9”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERPRES()=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?normcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “USEROUTCID=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:5] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?bypass”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“Local/9862230XXXX@from-internal-6583;2”, “USEROUTCID=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“Local/9862230XXXX@from-internal-6583;2”, “EMERGENCYCID=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“Local/9862230XXXX@from-internal-6583;2”, “TRUNKOUTCID=8622466042”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?trunkcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CALLERID(all)=8622466042)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“Local/9862230XXXX@from-internal-6583;2”, “0?sub-flp-9,s,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“Local/9862230XXXX@from-internal-6583;2”, “OUTNUM=1862230XXXX”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“Local/9862230XXXX@from-internal-6583;2”, “custom=SIP/fpbx-2-64804d09”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“Local/9862230XXXX@from-internal-6583;2”, “dialout-trunk-predial-hook,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/9862230XXXX@from-internal-6583;2”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?bypass,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CONNECTEDLINE(num,i)=1862230XXXX)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CONNECTEDLINE(name,i)=CID:8622466042)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?customtrunk”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“Local/9862230XXXX@from-internal-6583;2”, “SIP/fpbx-2-64804d09/1862230XXXX,300,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] netsock2.c: == Using SIP RTP TOS bits 184
[2012-02-26 08:56:52] VERBOSE[17059] netsock2.c: == Using SIP RTP CoS mark 5
[2012-02-26 08:56:52] VERBOSE[17059] app_dial.c: – Called SIP/fpbx-2-64804d09/1862230XXXX
[2012-02-26 08:56:52] VERBOSE[17059] app_dial.c: – SIP/fpbx-2-64804d09-00000011 is circuit-busy
[2012-02-26 08:56:52] VERBOSE[17059] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:23] NoOp(“Local/9862230XXXX@from-internal-6583;2”, “Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:24] Goto(“Local/9862230XXXX@from-internal-6583;2”, “s-CONGESTION,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,s-CONGESTION,1)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-6583;2”, “RC=1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:2] Goto(“Local/9862230XXXX@from-internal-6583;2”, “1,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,1,1)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [1@macro-dialout-trunk:1] Goto(“Local/9862230XXXX@from-internal-6583;2”, “continue,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [continue@macro-dialout-trunk:1] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?noreport”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,continue,3)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [continue@macro-dialout-trunk:3] NoOp(“Local/9862230XXXX@from-internal-6583;2”, “TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 1 - failing through to other trunks”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [continue@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “CALLERID(number)=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [9862230XXXX@from-internal:7] Macro(“Local/9862230XXXX@from-internal-6583;2”, “dialout-trunk,4,1862230XXXX,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK=4”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“Local/9862230XXXX@from-internal-6583;2”, “0?sub-pincheck,s,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?disabletrunk,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_NUMBER=1862230XXXX”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“Local/9862230XXXX@from-internal-6583;2”, “OUTBOUND_GROUP=OUT_4”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?nomax”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?skipoutcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“Local/9862230XXXX@from-internal-6583;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“Local/9862230XXXX@from-internal-6583;2”, “outbound-callerid,4”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERPRES()=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?normcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:4] Set(“Local/9862230XXXX@from-internal-6583;2”, “USEROUTCID=400”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:5] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?bypass”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“Local/9862230XXXX@from-internal-6583;2”, “USEROUTCID=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“Local/9862230XXXX@from-internal-6583;2”, “EMERGENCYCID=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“Local/9862230XXXX@from-internal-6583;2”, “TRUNKOUTCID=”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “1?trunkcid”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“Local/9862230XXXX@from-internal-6583;2”, “0?sub-flp-4,s,1()”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“Local/9862230XXXX@from-internal-6583;2”, “OUTNUM=1862230XXXX”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“Local/9862230XXXX@from-internal-6583;2”, “custom=SIP/voipms”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“Local/9862230XXXX@from-internal-6583;2”, “dialout-trunk-predial-hook,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/9862230XXXX@from-internal-6583;2”, “”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?bypass,1”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CONNECTEDLINE(num,i)=1862230XXXX)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“Local/9862230XXXX@from-internal-6583;2”, “1?Set(CONNECTEDLINE(name,i)=CID:400)”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“Local/9862230XXXX@from-internal-6583;2”, “0?customtrunk”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“Local/9862230XXXX@from-internal-6583;2”, “SIP/voipms/1862230XXXX,300,”) in new stack
[2012-02-26 08:56:52] VERBOSE[17059] netsock2.c: == Using SIP RTP TOS bits 184
[2012-02-26 08:56:52] VERBOSE[17059] netsock2.c: == Using SIP RTP CoS mark 5
[2012-02-26 08:56:52] VERBOSE[17059] app_dial.c: – Called SIP/voipms/1862230XXXX
[2012-02-26 08:56:54] VERBOSE[17059] app_dial.c: – SIP/voipms-00000012 is making progress passing it to Local/9862230XXXX@from-internal-6583;2
[2012-02-26 08:56:54] VERBOSE[17057] app_dial.c: – Local/9862230XXXX@from-internal-6583;1 is making progress passing it to SIP/400-0000000f
[2012-02-26 08:56:54] VERBOSE[17059] app_dial.c: – SIP/voipms-00000012 is ringing
[2012-02-26 08:56:54] VERBOSE[17057] app_dial.c: – Local/9862230XXXX@from-internal-6583;1 is ringing
[2012-02-26 08:57:05] VERBOSE[17059] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘Local/9862230XXXX@from-internal-6583;2’ in macro ‘dialout-trunk’
[2012-02-26 08:57:05] VERBOSE[17059] pbx.c: == Spawn extension (from-internal, 9862230XXXX, 7) exited non-zero on ‘Local/9862230XXXX@from-internal-6583;2’
[2012-02-26 08:57:05] VERBOSE[17059] pbx.c: – Executing [h@from-internal:1] Hangup(“Local/9862230XXXX@from-internal-6583;2”, “”) in new stack
[2012-02-26 08:57:05] VERBOSE[17059] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/9862230XXXX@from-internal-6583;2’
[2012-02-26 08:57:05] VERBOSE[17057] app_macro.c: == Spawn extension (macro-dial-one, s, 42) exited non-zero on ‘SIP/400-0000000f’ in macro ‘dial-one’
[2012-02-26 08:57:05] VERBOSE[17057] app_macro.c: == Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘SIP/400-0000000f’ in macro ‘exten-vm’
[2012-02-26 08:57:05] VERBOSE[17057] pbx.c: == Spawn extension (ext-local, 700, 2) exited non-zero on ‘SIP/400-0000000f’
[2012-02-26 08:57:05] VERBOSE[17057] pbx.c: – Executing [h@ext-local:1] Macro(“SIP/400-0000000f”, “hangupcall,”) in new stack
[2012-02-26 08:57:05] VERBOSE[17057] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/400-0000000f”, “1?theend”) in new stack
[2012-02-26 08:57:05] VERBOSE[17057] pbx.c: – Goto (macro-hangupcall,s,3)
[2012-02-26 08:57:05] VERBOSE[17057] pbx.c: – Executing [s@macro-hangupcall:3] Hangup(“SIP/400-0000000f”, “”) in new stack
[2012-02-26 08:57:05] VERBOSE[17057] app_macro.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on ‘SIP/400-0000000f’ in macro ‘hangupcall’
[2012-02-26 08:57:05] VERBOSE[17057] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/400-0000000f’
[2012-02-26 08:57:05] VERBOSE[17058] app_mixmonitor.c: == End MixMonitor Recording SIP/400-0000000f

NOW here’s some additional info that may help. Seeing the Follow Me under the custom extension extension didn’t work, I made a Ring Group 700 instead. My cell phone number was specified as the destination, 862230XXXX#. Now when I dial the Ring Group from an internal extension, the “Fixed CID Value” as set in “Change External CID Configuration” DOES work. Under this case, calls to my cell phone showed the desired fixed caller id. Making a call, this was the output of the Asterisk log. Again, XXXX is simply to obscure the last 4 digits - a simple case of “Replace All” in notepad.

2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:1] Macro(“SIP/400-0000000d”, “user-callerid,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/400-0000000d”, “AMPUSER=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/400-0000000d”, “0?report”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/400-0000000d”, “1?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/400-0000000d”, “AMPUSER=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/400-0000000d”, “AMPUSERCIDNAME=ShoIP1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/400-0000000d”, “0?report”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/400-0000000d”, “AMPUSERCID=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/400-0000000d”, “CALLERID(all)=“ShoIP1” <400>”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:9] GotoIf(“SIP/400-0000000d”, “0?limit”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:10] ExecIf(“SIP/400-0000000d”, “0?Set(GROUP(concurrency_limit)=400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:11] ExecIf(“SIP/400-0000000d”, “0?Set(CHANNEL(language)=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:12] GosubIf(“SIP/400-0000000d”, “0?sub-ccss,s,1(from-internal,700)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SIP/400-0000000d”, “0?continue”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:14] Set(“SIP/400-0000000d”, “__TTL=64”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“SIP/400-0000000d”, “1?continue”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (macro-user-callerid,s,26)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/400-0000000d”, “CALLERID(number)=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/400-0000000d”, “CALLERID(name)=ShoIP1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/400-0000000d”, “CHANNEL(language)=en”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:2] Macro(“SIP/400-0000000d”, “blkvm-setifempty,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/400-0000000d”, “1?init”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-blkvm-setifempty:4] Set(“SIP/400-0000000d”, “__BLKVM_CHANNEL=SIP/400-0000000d”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-blkvm-setifempty:5] Set(“SIP/400-0000000d”, “SHARED(BLKVM,SIP/400-0000000d)=TRUE”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-blkvm-setifempty:6] Set(“SIP/400-0000000d”, “GOSUB_RETVAL=TRUE”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/400-0000000d”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:3] GotoIf(“SIP/400-0000000d”, “1?skipov”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (from-internal,700,6)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:6] Set(“SIP/400-0000000d”, “RRNODEST=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:7] Set(“SIP/400-0000000d”, “__NODEST=700”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:8] GosubIf(“SIP/400-0000000d”, “1?sub-rgsetcid,s,1()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-rgsetcid:1] Goto(“SIP/400-0000000d”, “s-fixed,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (sub-rgsetcid,s-fixed,1)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s-fixed@sub-rgsetcid:1] ExecIf(“SIP/400-0000000d”, “1?Set(__TRUNKCIDOVERRIDE=973457XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s-fixed@sub-rgsetcid:2] Return(“SIP/400-0000000d”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:9] Gosub(“SIP/400-0000000d”, “sub-record-check,s,1(rg,700,always)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/400-0000000d”, “1?check”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (sub-record-check,s,3)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/400-0000000d”, “MON_FMT=wav”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:4] GotoIf(“SIP/400-0000000d”, “1?next”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (sub-record-check,s,7)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:7] ExecIf(“SIP/400-0000000d”, “0?Return()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“SIP/400-0000000d”, “0?rg,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/400-0000000d”, “__REC_STATUS=INITIALIZED”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:10] ExecIf(“SIP/400-0000000d”, “1?Set(__REC_POLICY_MODE=always)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:11] Set(“SIP/400-0000000d”, “NOW=1330266877”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/400-0000000d”, “__DAY=26”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:13] Set(“SIP/400-0000000d”, “__MONTH=02”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/400-0000000d”, “__YEAR=2012”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/400-0000000d”, “__TIMESTR=20120226-093437”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/400-0000000d”, “__FROMEXTEN=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/400-0000000d”, “__CALLFILENAME=rg-700-400-20120226-093437-1330266877.20”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@sub-record-check:18] Goto(“SIP/400-0000000d”, “rg,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (sub-record-check,rg,1)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [rg@sub-record-check:1] GosubIf(“SIP/400-0000000d”, “1?record,1(rg,always,400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [record@sub-record-check:1] Set(“SIP/400-0000000d”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [record@sub-record-check:2] MixMonitor(“SIP/400-0000000d”, “2012/02/26/rg-700-400-20120226-093437-1330266877.20.wav,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [record@sub-record-check:3] Set(“SIP/400-0000000d”, “__REC_STATUS=RECORDING”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [record@sub-record-check:4] Set(“SIP/400-0000000d”, “CDR(recordingfile)=rg-700-400-20120226-093437-1330266877.20.wav”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [record@sub-record-check:5] Return(“SIP/400-0000000d”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [rg@sub-record-check:2] Return(“SIP/400-0000000d”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:10] Set(“SIP/400-0000000d”, “RingGroupMethod=ringall”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [700@from-internal:11] Macro(“SIP/400-0000000d”, “dial,30,tr,9862230XXXX#”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-dial:1] GotoIf(“SIP/400-0000000d”, “1?dial”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Goto (macro-dial,s,3)
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-dial:3] AGI(“SIP/400-0000000d”, “dialparties.agi”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2012-02-26 09:34:37] VERBOSE[21647] app_mixmonitor.c: == Begin MixMonitor Recording SIP/400-0000000d
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: dialparties.agi: Caller ID name is ‘ShoIP1’ number is ‘400’
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: – dialparties.agi: Added extension 9862230XXXX# to extension map
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: – dialparties.agi: Extension 9862230XXXX# cf is disabled
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: – dialparties.agi: Filtered ARG3: 9862230XXXX
[2012-02-26 09:34:37] VERBOSE[21646] res_agi.c: – <SIP/400-0000000d>AGI Script dialparties.agi completed, returning 0
[2012-02-26 09:34:37] VERBOSE[21646] pbx.c: – Executing [s@macro-dial:7] Dial(“SIP/400-0000000d”, “Local/9862230XXXX@from-internal/n,30,trM(auto-blkvm)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21646] app_dial.c: – Called Local/9862230XXXX@from-internal/n
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [9862230XXXX@from-internal:1] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “user-callerid,LIMIT,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:1] Set(“Local/9862230XXXX@from-internal-0d77;2”, “AMPUSER=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?report”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “AMPUSER=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:5] Set(“Local/9862230XXXX@from-internal-0d77;2”, “AMPUSERCIDNAME=ShoIP1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?report”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:7] Set(“Local/9862230XXXX@from-internal-0d77;2”, “AMPUSERCID=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:8] Set(“Local/9862230XXXX@from-internal-0d77;2”, “CALLERID(all)=“ShoIP1” <400>”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?limit”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:10] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(GROUP(concurrency_limit)=400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:11] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CHANNEL(language)=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:12] GosubIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?sub-ccss,s,1(from-internal,)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?continue”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-user-callerid,s,26)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:26] Set(“Local/9862230XXXX@from-internal-0d77;2”, “CALLERID(number)=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:27] Set(“Local/9862230XXXX@from-internal-0d77;2”, “CALLERID(name)=ShoIP1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-user-callerid:28] Set(“Local/9862230XXXX@from-internal-0d77;2”, “CHANNEL(language)=en”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [9862230XXXX@from-internal:2] Set(“Local/9862230XXXX@from-internal-0d77;2”, “MOHCLASS=default”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [9862230XXXX@from-internal:3] Set(“Local/9862230XXXX@from-internal-0d77;2”, “_NODEST=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [9862230XXXX@from-internal:4] Gosub(“Local/9862230XXXX@from-internal-0d77;2”, “sub-record-check,s,1(out,9862230XXXX,)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?check”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (sub-record-check,s,3)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@sub-record-check:3] Set(“Local/9862230XXXX@from-internal-0d77;2”, “MON_FMT=wav”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@sub-record-check:4] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?next”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@sub-record-check:5] Set(“Local/9862230XXXX@from-internal-0d77;2”, “CDR(recordingfile)=rg-700-400-20120226-093437-1330266877.20.wav”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@sub-record-check:6] Return(“Local/9862230XXXX@from-internal-0d77;2”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [9862230XXXX@from-internal:5] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “dialout-trunk,8,1862230XXXX,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK=8”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?sub-pincheck,s,1()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?disabletrunk,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_NUMBER=1862230XXXX”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“Local/9862230XXXX@from-internal-0d77;2”, “OUTBOUND_GROUP=OUT_8”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?nomax”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?skipoutcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “outbound-callerid,8”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERPRES()=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?normcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “USEROUTCID=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:5] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?bypass”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“Local/9862230XXXX@from-internal-0d77;2”, “USEROUTCID=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“Local/9862230XXXX@from-internal-0d77;2”, “EMERGENCYCID=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“Local/9862230XXXX@from-internal-0d77;2”, “TRUNKOUTCID=8622466042”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?trunkcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CALLERID(all)=8622466042)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CALLERID(all)=973457XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?sub-flp-8,s,1()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“Local/9862230XXXX@from-internal-0d77;2”, “OUTNUM=1862230XXXX”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“Local/9862230XXXX@from-internal-0d77;2”, “custom=SIP/fpbx-1-64804d09”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “dialout-trunk-predial-hook,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/9862230XXXX@from-internal-0d77;2”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?bypass,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CONNECTEDLINE(num,i)=1862230XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CONNECTEDLINE(name,i)=CID:973457XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?customtrunk”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“Local/9862230XXXX@from-internal-0d77;2”, “SIP/fpbx-1-64804d09/1862230XXXX,300,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] netsock2.c: == Using SIP RTP TOS bits 184
[2012-02-26 09:34:37] VERBOSE[21649] netsock2.c: == Using SIP RTP CoS mark 5
[2012-02-26 09:34:37] VERBOSE[21649] app_dial.c: – Called SIP/fpbx-1-64804d09/1862230XXXX
[2012-02-26 09:34:37] VERBOSE[21649] app_dial.c: – SIP/fpbx-1-64804d09-0000000e is circuit-busy
[2012-02-26 09:34:37] VERBOSE[21649] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:23] NoOp(“Local/9862230XXXX@from-internal-0d77;2”, “Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:24] Goto(“Local/9862230XXXX@from-internal-0d77;2”, “s-CONGESTION,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,s-CONGESTION,1)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-0d77;2”, “RC=1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:2] Goto(“Local/9862230XXXX@from-internal-0d77;2”, “1,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,1,1)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [1@macro-dialout-trunk:1] Goto(“Local/9862230XXXX@from-internal-0d77;2”, “continue,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [continue@macro-dialout-trunk:1] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?noreport”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,continue,3)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [continue@macro-dialout-trunk:3] NoOp(“Local/9862230XXXX@from-internal-0d77;2”, “TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 1 - failing through to other trunks”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [continue@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “CALLERID(number)=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [9862230XXXX@from-internal:6] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “dialout-trunk,9,1862230XXXX,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK=9”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?sub-pincheck,s,1()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?disabletrunk,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_NUMBER=1862230XXXX”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“Local/9862230XXXX@from-internal-0d77;2”, “OUTBOUND_GROUP=OUT_9”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?nomax”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?skipoutcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “outbound-callerid,9”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERPRES()=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?normcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “USEROUTCID=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:5] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?bypass”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“Local/9862230XXXX@from-internal-0d77;2”, “USEROUTCID=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“Local/9862230XXXX@from-internal-0d77;2”, “EMERGENCYCID=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“Local/9862230XXXX@from-internal-0d77;2”, “TRUNKOUTCID=8622466042”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?trunkcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CALLERID(all)=8622466042)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CALLERID(all)=973457XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?sub-flp-9,s,1()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“Local/9862230XXXX@from-internal-0d77;2”, “OUTNUM=1862230XXXX”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“Local/9862230XXXX@from-internal-0d77;2”, “custom=SIP/fpbx-2-64804d09”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “dialout-trunk-predial-hook,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/9862230XXXX@from-internal-0d77;2”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?bypass,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CONNECTEDLINE(num,i)=1862230XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CONNECTEDLINE(name,i)=CID:973457XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?customtrunk”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“Local/9862230XXXX@from-internal-0d77;2”, “SIP/fpbx-2-64804d09/1862230XXXX,300,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] netsock2.c: == Using SIP RTP TOS bits 184
[2012-02-26 09:34:37] VERBOSE[21649] netsock2.c: == Using SIP RTP CoS mark 5
[2012-02-26 09:34:37] VERBOSE[21649] app_dial.c: – Called SIP/fpbx-2-64804d09/1862230XXXX
[2012-02-26 09:34:37] VERBOSE[21649] app_dial.c: – SIP/fpbx-2-64804d09-0000000f is circuit-busy
[2012-02-26 09:34:37] VERBOSE[21649] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:23] NoOp(“Local/9862230XXXX@from-internal-0d77;2”, “Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:24] Goto(“Local/9862230XXXX@from-internal-0d77;2”, “s-CONGESTION,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,s-CONGESTION,1)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-0d77;2”, “RC=1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:2] Goto(“Local/9862230XXXX@from-internal-0d77;2”, “1,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,1,1)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [1@macro-dialout-trunk:1] Goto(“Local/9862230XXXX@from-internal-0d77;2”, “continue,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [continue@macro-dialout-trunk:1] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?noreport”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,continue,3)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [continue@macro-dialout-trunk:3] NoOp(“Local/9862230XXXX@from-internal-0d77;2”, “TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 1 - failing through to other trunks”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [continue@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “CALLERID(number)=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [9862230XXXX@from-internal:7] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “dialout-trunk,4,1862230XXXX,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK=4”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?sub-pincheck,s,1()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?disabletrunk,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_NUMBER=1862230XXXX”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“Local/9862230XXXX@from-internal-0d77;2”, “OUTBOUND_GROUP=OUT_4”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?nomax”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?skipoutcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“Local/9862230XXXX@from-internal-0d77;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “outbound-callerid,4”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERPRES()=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(REALCALLERIDNUM=400)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?normcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:4] Set(“Local/9862230XXXX@from-internal-0d77;2”, “USEROUTCID=400”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:5] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?bypass”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“Local/9862230XXXX@from-internal-0d77;2”, “USEROUTCID=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“Local/9862230XXXX@from-internal-0d77;2”, “EMERGENCYCID=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“Local/9862230XXXX@from-internal-0d77;2”, “TRUNKOUTCID=”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?trunkcid”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CALLERID(all)=973457XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?sub-flp-4,s,1()”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“Local/9862230XXXX@from-internal-0d77;2”, “OUTNUM=1862230XXXX”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“Local/9862230XXXX@from-internal-0d77;2”, “custom=SIP/voipms”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“Local/9862230XXXX@from-internal-0d77;2”, “dialout-trunk-predial-hook,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/9862230XXXX@from-internal-0d77;2”, “”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?bypass,1”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CONNECTEDLINE(num,i)=1862230XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“Local/9862230XXXX@from-internal-0d77;2”, “1?Set(CONNECTEDLINE(name,i)=CID:973457XXXX)”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“Local/9862230XXXX@from-internal-0d77;2”, “0?customtrunk”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“Local/9862230XXXX@from-internal-0d77;2”, “SIP/voipms/1862230XXXX,300,”) in new stack
[2012-02-26 09:34:37] VERBOSE[21649] netsock2.c: == Using SIP RTP TOS bits 184
[2012-02-26 09:34:37] VERBOSE[21649] netsock2.c: == Using SIP RTP CoS mark 5
[2012-02-26 09:34:37] VERBOSE[21649] app_dial.c: – Called SIP/voipms/1862230XXXX
[2012-02-26 09:34:38] VERBOSE[21649] app_dial.c: – SIP/voipms-00000010 is making progress passing it to Local/9862230XXXX@from-internal-0d77;2
[2012-02-26 09:34:38] VERBOSE[21646] app_dial.c: – Local/9862230XXXX@from-internal-0d77;1 is making progress passing it to SIP/400-0000000d
[2012-02-26 09:34:47] VERBOSE[21646] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/400-0000000d’ in macro ‘dial’
[2012-02-26 09:34:47] VERBOSE[21646] pbx.c: == Spawn extension (from-internal, 700, 11) exited non-zero on ‘SIP/400-0000000d’
[2012-02-26 09:34:47] VERBOSE[21646] pbx.c: – Executing [h@from-internal:1] Hangup(“SIP/400-0000000d”, “”) in new stack
[2012-02-26 09:34:47] VERBOSE[21646] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/400-0000000d’
[2012-02-26 09:34:47] VERBOSE[21649] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘Local/9862230XXXX@from-internal-0d77;2’ in macro ‘dialout-trunk’
[2012-02-26 09:34:47] VERBOSE[21649] pbx.c: == Spawn extension (from-internal, 9862230XXXX, 7) exited non-zero on ‘Local/9862230XXXX@from-internal-0d77;2’
[2012-02-26 09:34:47] VERBOSE[21649] pbx.c: – Executing [h@from-internal:1] Hangup(“Local/9862230XXXX@from-internal-0d77;2”, “”) in new stack
[2012-02-26 09:34:47] VERBOSE[21649] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/9862230XXXX@from-internal-0d77;2’
[2012-02-26 09:34:47] VERBOSE[21647] app_mixmonitor.c: == End MixMonitor Recording SIP/400-0000000d