"Change External CID Configuration" ineffective for Follow Me 2.10.0.5

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 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: – 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

Maybe there’s a bug within the follow me? The Ring group module is 2.10.0.3.

Hi all,
I’m posting here because I have the same (or similar) problem and I didn’t find the solution.
I need to:

  • receive a call on a number configured on my FreePBX 2.10.1.9
  • the call goes to a Ring Group with only one mobile-number suffixed by a #
  • in this Ring Group I set Change External CID Configuration at Fixed CID Value (writing 8888 in the value)
  • the Outbound Route has the wildcard in the “match pattern” field and 8888 in the CalleID field
    But the call ends without calling the mobile-phone.

Here a log of a call coming from 393XXXXXXX to 0583155XXXX wich isn’t forwarded to 349XXXXXXX, the caller hears a tone and the call fails.
Could you help me understanding where is the problem?

THANK YOU!


– Executing [0583155XXXX@from-trunk:1] Set(“SIP/Ammin-00000218”, “__FROM_DID=0583155XXXX”) in new stack
– Executing [0583155XXXX@from-trunk:2] Set(“SIP/Ammin-00000218”, “CDR(did)=0583155XXXX”) in new stack
– Executing [0583155XXXX@from-trunk:3] ExecIf(“SIP/Ammin-00000218”, “0 ?Set(CALLERID(name)=939XXXXXXX)”) in new stack
– Executing [0583155XXXX@from-trunk:4] Set(“SIP/Ammin-00000218”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [0583155XXXX@from-trunk:5] Set(“SIP/Ammin-00000218”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [0583155XXXX@from-trunk:6] Goto(“SIP/Ammin-00000218”, “ext-group,611,1”) in new stack
– Goto (ext-group,611,1)
– Executing [611@ext-group:1] Macro(“SIP/Ammin-00000218”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/Ammin-00000218”, “AMPUSER=939XXXXXXX”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/Ammin-00000218”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/Ammin-00000218”, “1?Set(REALCALLERIDNUM=939XXXXXXX)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/Ammin-00000218”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/Ammin-00000218”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/Ammin-00000218”, “1?report”) in new stack
– Goto (macro-user-callerid,s,11)
– Executing [s@macro-user-callerid:11] GotoIf(“SIP/Ammin-00000218”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:12] Set(“SIP/Ammin-00000218”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:13] GotoIf(“SIP/Ammin-00000218”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,24)
– Executing [s@macro-user-callerid:24] Set(“SIP/Ammin-00000218”, “CALLERID(number)=939XXXXXXX”) in new stack
– Executing [s@macro-user-callerid:25] Set(“SIP/Ammin-00000218”, “CALLERID(name)=939XXXXXXX”) in new stack
– Executing [s@macro-user-callerid:26] Set(“SIP/Ammin-00000218”, “CHANNEL(language)=en”) in new stack
– Executing [611@ext-group:2] Macro(“SIP/Ammin-00000218”, “blkvm-setifempty,”) in new stack
– Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/Ammin-00000218”, “1?init”) in new stack
– Goto (macro-blkvm-setifempty,s,4)
– Executing [s@macro-blkvm-setifempty:4] Set(“SIP/Ammin-00000218”, “__BLKVM_CHANNEL=SIP/Ammin-00000218”) in new stack
– Executing [s@macro-blkvm-setifempty:5] Set(“SIP/Ammin-00000218”, “SHARED(BLKVM,SIP/Ammin-00000218)=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:6] Set(“SIP/Ammin-00000218”, “GOSUB_RETVAL=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/Ammin-00000218”, “”) in new stack
– Executing [611@ext-group:3] GotoIf(“SIP/Ammin-00000218”, “1?skipov”) in new stack
– Goto (ext-group,611,6)
– Executing [611@ext-group:6] Set(“SIP/Ammin-00000218”, “RRNODEST=”) in new stack
– Executing [611@ext-group:7] Set(“SIP/Ammin-00000218”, “__NODEST=611”) in new stack
– Executing [611@ext-group:8] GosubIf(“SIP/Ammin-00000218”, “1?sub-rgsetcid,s,1()”) in new stack
– Executing [s@sub-rgsetcid:1] Goto(“SIP/Ammin-00000218”, “s-fixed,1”) in new stack
– Goto (sub-rgsetcid,s-fixed,1)
– Executing [s-fixed@sub-rgsetcid:1] ExecIf(“SIP/Ammin-00000218”, “1?Set(__TRUNKCIDOVERRIDE=888888)”) in new stack
– Executing [s-fixed@sub-rgsetcid:2] Return(“SIP/Ammin-00000218”, “”) in new stack
– Executing [611@ext-group:9] Gosub(“SIP/Ammin-00000218”, “sub-record-check,s,1(rg,611,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/Ammin-00000218”, “1?check”) in new stack
– Goto (sub-record-check,s,6)
– Executing [s@sub-record-check:6] Set(“SIP/Ammin-00000218”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:7] GotoIf(“SIP/Ammin-00000218”, “1?next”) in new stack
– Goto (sub-record-check,s,10)
– Executing [s@sub-record-check:10] ExecIf(“SIP/Ammin-00000218”, “0?Return()”) in new stack
– Executing [s@sub-record-check:11] GotoIf(“SIP/Ammin-00000218”, “0?rg,1”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/Ammin-00000218”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/Ammin-00000218”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
– Executing [s@sub-record-check:14] Set(“SIP/Ammin-00000218”, “NOW=1371049810”) in new stack
– Executing [s@sub-record-check:15] Set(“SIP/Ammin-00000218”, “__DAY=12”) in new stack
– Executing [s@sub-record-check:16] Set(“SIP/Ammin-00000218”, “__MONTH=06”) in new stack
– Executing [s@sub-record-check:17] Set(“SIP/Ammin-00000218”, “__YEAR=2013”) in new stack
– Executing [s@sub-record-check:18] Set(“SIP/Ammin-00000218”, “__TIMESTR=20130612-171010”) in new stack
– Executing [s@sub-record-check:19] Set(“SIP/Ammin-00000218”, “__FROMEXTEN=939XXXXXXX”) in new stack
– Executing [s@sub-record-check:20] Set(“SIP/Ammin-00000218”, “__CALLFILENAME=rg-611-939XXXXXXX-20130612-171010-1371049810.622”) in new stack
– Executing [s@sub-record-check:21] Goto(“SIP/Ammin-00000218”, “rg,1”) in new stack
– Goto (sub-record-check,rg,1)
– Executing [rg@sub-record-check:1] GosubIf(“SIP/Ammin-00000218”, “0?record,1(rg,dontcare,939XXXXXXX)”) in new stack
– Executing [rg@sub-record-check:2] Return(“SIP/Ammin-00000218”, “”) in new stack
– Executing [611@ext-group:10] Set(“SIP/Ammin-00000218”, “RingGroupMethod=ringall”) in new stack
– Executing [611@ext-group:11] Macro(“SIP/Ammin-00000218”, “dial,20,tTr,349XXXXXXX#”) in new stack
– Executing [s@macro-dial:1] GotoIf(“SIP/Ammin-00000218”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [s@macro-dial:3] AGI(“SIP/Ammin-00000218”, “dialparties.agi”) in new stack
– Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘939XXXXXXX’ number is ‘939XXXXXXX’
> dialparties.agi: USE_CONFIRMATION: ‘FALSE’
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 349XXXXXXX# to extension map
– dialparties.agi: Extension 349XXXXXXX# cf is disabled
> dialparties.agi: extnum 349XXXXXXX# has: cw: 0; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: Built External dialstring component for 349XXXXXXX: Local/349XXXXXXX@from-internal/n
– dialparties.agi: Filtered ARG3: 349XXXXXXX
> dialparties.agi: NODEST: 611 adding M(auto-blkvm) to dialopts: tTrM(auto-blkvm)
> dialparties.agi: NODEST: 611 blkvm enabled macro already in dialopts: tTrM(auto-blkvm)
– <SIP/Ammin-00000218>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“SIP/Ammin-00000218”, “Local/349XXXXXXX@from-internal/n,20,tTrM(auto-blkvm)”) in new stack
– Called Local/349XXXXXXX@from-internal/n
– Executing [349XXXXXXX@from-internal:1] Macro(“Local/349XXXXXXX@from-internal-95a7;2”, “user-callerid,LIMIT,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“Local/349XXXXXXX@from-internal-95a7;2”, “AMPUSER=939XXXXXXX”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“Local/349XXXXXXX@from-internal-95a7;2”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“Local/349XXXXXXX@from-internal-95a7;2”, “1?Set(REALCALLERIDNUM=939XXXXXXX)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“Local/349XXXXXXX@from-internal-95a7;2”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“Local/349XXXXXXX@from-internal-95a7;2”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“Local/349XXXXXXX@from-internal-95a7;2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,11)
– Executing [s@macro-user-callerid:11] GotoIf(“Local/349XXXXXXX@from-internal-95a7;2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,24)
– Executing [s@macro-user-callerid:24] Set(“Local/349XXXXXXX@from-internal-95a7;2”, “CALLERID(number)=939XXXXXXX”) in new stack
– Executing [s@macro-user-callerid:25] Set(“Local/349XXXXXXX@from-internal-95a7;2”, “CALLERID(name)=939XXXXXXX”) in new stack
– Executing [s@macro-user-callerid:26] Set(“Local/349XXXXXXX@from-internal-95a7;2”, “CHANNEL(language)=en”) in new stack
– Executing [349XXXXXXX@from-internal:2] NoCDR(“Local/349XXXXXXX@from-internal-95a7;2”, “”) in new stack
– Executing [349XXXXXXX@from-internal:3] Progress(“Local/349XXXXXXX@from-internal-95a7;2”, “”) in new stack
– Executing [349XXXXXXX@from-internal:4] Wait(“Local/349XXXXXXX@from-internal-95a7;2”, “1”) in new stack
– Local/349XXXXXXX@from-internal-95a7;1 is making progress passing it to SIP/Ammin-00000218
– Executing [349XXXXXXX@from-internal:5] Progress(“Local/349XXXXXXX@from-internal-95a7;2”, “”) in new stack
– Executing [349XXXXXXX@from-internal:6] Playback(“Local/349XXXXXXX@from-internal-95a7;2”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
– <Local/349XXXXXXX@from-internal-95a7;2> Playing ‘silence/1.gsm’ (language ‘en’)
– Local/349XXXXXXX@from-internal-95a7;1 is making progress passing it to SIP/Ammin-00000218
[2013-06-12 17:10:13] WARNING[16514]: file.c:663 ast_openstream_full: File cannot-complete-as-dialed does not exist in any format
[2013-06-12 17:10:13] WARNING[16514]: file.c:958 ast_streamfile: Unable to open cannot-complete-as-dialed (format 0x8 (alaw)): No such file or directory
[2013-06-12 17:10:13] WARNING[16514]: app_playback.c:475 playback_exec: ast_streamfile failed on Local/349XXXXXXX@from-internal-95a7;2 for silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer
[2013-06-12 17:10:13] WARNING[16514]: file.c:663 ast_openstream_full: File check-number-dial-again does not exist in any format
[2013-06-12 17:10:13] WARNING[16514]: file.c:958 ast_streamfile: Unable to open check-number-dial-again (format 0x8 (alaw)): No such file or directory
[2013-06-12 17:10:13] WARNING[16514]: app_playback.c:475 playback_exec: ast_streamfile failed on Local/349XXXXXXX@from-internal-95a7;2 for silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer
– Executing [349XXXXXXX@from-internal:7] Wait(“Local/349XXXXXXX@from-internal-95a7;2”, “1”) in new stack
– Executing [349XXXXXXX@from-internal:8] Congestion(“Local/349XXXXXXX@from-internal-95a7;2”, “20”) in new stack
– Local/349XXXXXXX@from-internal-95a7;1 is circuit-busy
== Everyone is busy/congested at this time (1:0/1/0)
– Executing [s@macro-dial:8] Set(“SIP/Ammin-00000218”, “DIALSTATUS=CONGESTION”) in new stack
– Executing [s@macro-dial:9] GosubIf(“SIP/Ammin-00000218”, “0?CONGESTION,1”) in new stack
– Executing [611@ext-group:12] Gosub(“SIP/Ammin-00000218”, “sub-record-cancel,s,1()”) in new stack
– Executing [s@sub-record-cancel:1] ExecIf(“SIP/Ammin-00000218”, “1?Return()”) in new stack
– Executing [611@ext-group:13] Set(“SIP/Ammin-00000218”, “RingGroupMethod=”) in new stack
– Executing [611@ext-group:14] GotoIf(“SIP/Ammin-00000218”, “0?nodest”) in new stack
– Executing [611@ext-group:15] Set(“SIP/Ammin-00000218”, “__NODEST=”) in new stack
– Executing [611@ext-group:16] Macro(“SIP/Ammin-00000218”, “blkvm-clr,”) in new stack
– Executing [s@macro-blkvm-clr:1] Set(“SIP/Ammin-00000218”, “SHARED(BLKVM,SIP/Ammin-00000218)=”) in new stack
– Executing [s@macro-blkvm-clr:2] Set(“SIP/Ammin-00000218”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/Ammin-00000218”, “”) in new stack
– Executing [611@ext-group:17] Goto(“SIP/Ammin-00000218”, “app-blackhole,hangup,1”) in new stack
– Goto (app-blackhole,hangup,1)
– Executing [hangup@app-blackhole:1] NoOp(“SIP/Ammin-00000218”, “Blackhole Dest: Hangup”) in new stack
– Executing [hangup@app-blackhole:2] Hangup(“SIP/Ammin-00000218”, “”) in new stack
== Spawn extension (app-blackhole, hangup, 2) exited non-zero on ‘SIP/Ammin-00000218’
== Spawn extension (from-internal, 349XXXXXXX, 8) exited non-zero on ‘Local/349XXXXXXX@from-internal-95a7;2’
– Executing [h@from-internal:1] Hangup(“Local/349XXXXXXX@from-internal-95a7;2”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/349XXXXXXX@from-internal-95a7;2’