This was posted on 02/26/2012 and received no answers in the forum.
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.
