История изменений [назад]

FreePBX не работают исходящие!

Ситуация такая. Asterisk + FreePBX 13, 14 транков мегафон входящие работают прекрасно а вот исходящие должны проходить по одному номеру. Так и настроено в исходящей.(но по какой то причине при попытке позвонить в любое направление кроме внутренних вызов скидывается) Лог прилагаю

[2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:1] Macro("SIP/500-00000077", "user-callerid,LIMIT,EXTERNAL,") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/500-00000077", "TOUCHMONITOR=1470993657.119") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/500-00000077", "AMPUSER=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/500-00000077", "0?report") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/500-00000077", "1?Set(REALCALLERIDNUM=500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/500-00000077", "AMPUSER=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/500-00000077", "0?limit") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/500-00000077", "AMPUSERCIDNAME=????????? ???????") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/500-00000077", "0?report") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/500-00000077", "AMPUSERCID=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/500-00000077", "DIALOPTIONS=Ttr") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/500-00000077", "CALLERID(all)="????????? ???????" <500>") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/500-00000077", "0?limit") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/500-00000077", "1?Set(GROUP(concurrencylimit)=500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/500-00000077", "1?Set(CHANNEL(language)=en)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("SIP/500-00000077", "1?continue") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: Goto (macro-user-callerid,s,29) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:29] Set("SIP/500-00000077", "CALLERID(number)=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/500-00000077", "CALLERID(name)=????????? ???????") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/500-00000077", "CDR(cnum)=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/500-00000077", "CDR(cnam)=????????? ???????") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/500-00000077", "CHANNEL(language)=en") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:2] Gosub("SIP/500-00000077", "sub-record-check,s,1(out,89384848169,yes)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/500-00000077", "0?initialized") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:2] Set("SIP/500-00000077", "RECSTATUS=INITIALIZED") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:3] Set("SIP/500-00000077", "NOW=1470993657") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:4] Set("SIP/500-00000077", "DAY=12") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:5] Set("SIP/500-00000077", "MONTH=08") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:6] Set("SIP/500-00000077", "YEAR=2016") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:7] Set("SIP/500-00000077", "TIMESTR=20160812-122057") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:8] Set("SIP/500-00000077", "FROMEXTEN=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:9] Set("SIP/500-00000077", "MONFMT=WAV") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/500-00000077", "Recordings initialized") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/500-00000077", "0?Set(ARG3=dontcare)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:12] Set("SIP/500-00000077", "RECPOLICYMODESAVE=") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/500-00000077", "0?Set(RECSTATUS=NO)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/500-00000077", "3?checkaction") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: Goto (sub-record-check,s,17) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/500-00000077", "1?sub-record-check,out,1") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: Goto (sub-record-check,out,1) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/500-00000077", "Outbound Recording Check from 500 to 89384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:2] Set("SIP/500-00000077", "RECMODE=yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/500-00000077", "0?Goto(routewins)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:4] ExecIf("SIP/500-00000077", "0?Goto(routewins)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:5] Gosub("SIP/500-00000077", "recordcheck,1(yes,out,89384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/500-00000077", "Starting recording check against yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/500-00000077", "yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: Goto (sub-record-check,recordcheck,9) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("SIP/500-00000077", "0?Return()") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:10] Set("SIP/500-00000077", "RECPOLICYMODE=YES") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("SIP/500-00000077", "startrec") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: Goto (sub-record-check,recordcheck,16) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/500-00000077", "Starting recording: out, 89384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:17] Set("SIP/500-00000077", "AUDIOHOOKINHERIT(MixMonitor)=yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:18] Set("SIP/500-00000077", "CALLFILENAME=out-89384848169-500-20160812-122057-1470993657.119") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/500-00000077", "2016/08/12/out-89384848169-500-20160812-122057-1470993657.119.WAV,ai(LOCALMIXMONID),") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:20] Set("SIP/500-00000077", "MIXMONID=0xb224b4e0") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:21] Set("SIP/500-00000077", "RECORDID=SIP/500-00000077") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:22] Set("SIP/500-00000077", "RECSTATUS=RECORDING") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:23] Set("SIP/500-00000077", "CDR(recordingfile)=out-89384848169-500-20160812-122057-1470993657.119.WAV") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:24] Return("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:6] Return("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:3] ExecIf("SIP/500-00000077", "0 ?Set(CDR(accountcode)=)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:4] Set("SIP/500-00000077", "ROUTECIDSAVE="????????? ???????" <500>") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:5] Set("SIP/500-00000077", "EMERGENCYROUTE=YES") in new stack [2016-08-12 12:20:57] VERBOSE[30742][C-00000036] appmixmonitor.c: Begin MixMonitor Recording SIP/500-00000077 [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:6] Set("SIP/500-00000077", "INTRACOMPANYROUTE=YES") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:7] Set("SIP/500-00000077", "MOHCLASS=default") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:8] ExecIf("SIP/500-00000077", "1?Set(TRUNKCIDOVERRIDE=79385450740)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:9] Set("SIP/500-00000077", "NODEST=") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:10] Macro("SIP/500-00000077", "dialout-trunk,7,79384848169,,off") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/500-00000077", "DIALTRUNK=7") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf("SIP/500-00000077", "0?sub-pincheck,s,1()") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf("SIP/500-00000077", "0?disabletrunk,1") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:4] Set("SIP/500-00000077", "DIALNUMBER=79384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:5] Set("SIP/500-00000077", "DIALTRUNKOPTIONS=Ttr") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/500-00000077", "OUTBOUNDGROUP=OUT7") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("SIP/500-00000077", "1?nomax") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: Goto (macro-dialout-trunk,s,9) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("SIP/500-00000077", "1?skipoutcid") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: Goto (macro-dialout-trunk,s,12) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf("SIP/500-00000077", "1?sub-flp-7,s,1()") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:1] ExecIf("SIP/500-00000077", "0?Set(TARGETFLP7=786179384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:2] GotoIf("SIP/500-00000077", "0?match") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:3] ExecIf("SIP/500-00000077", "0?Set(TARGETFLP7=79384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:4] GotoIf("SIP/500-00000077", "0?match") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:5] Return("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:13] Set("SIP/500-00000077", "OUTNUM=79384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:14] Set("SIP/500-00000077", "custom=SIP/79385450740") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf("SIP/500-00000077", "0?Set(DIALTRUNKOPTIONS=M(setmusic^default)Ttr)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("SIP/500-00000077", "0?Set(DIALTRUNKOPTIONS=TtrM(confirm))") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:17] Macro("SIP/500-00000077", "dialout-trunk-predial-hook,") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf("SIP/500-00000077", "0?bypass,1") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf("SIP/500-00000077", "1?Set(CONNECTEDLINE(num,i)=79384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("SIP/500-00000077", "1?Set(CONNECTEDLINE(name,i)=CID:500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("SIP/500-00000077", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf("SIP/500-00000077", "0?customtrunk") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:23] Dial("SIP/500-00000077", "SIP/79385450740/79384848169,300,Ttr") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] netsock2.c: Using SIP RTP CoS mark 5 [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] appdial.c: Called SIP/79385450740/79384848169 [2016-08-12 12:20:58] WARNING[3723][C-00000036] chan_sip.c: Received response: "Forbidden" from '"????????? ???????" <sip:500@10.0.0.28>;tag=as40fe2082'

FreePBX не работают исходящие!

[2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:1] Macro("SIP/500-00000077", "user-callerid,LIMIT,EXTERNAL,") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/500-00000077", "TOUCHMONITOR=1470993657.119") "TOUCH_MONITOR=1470993657.119") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/500-00000077", "AMPUSER=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/500-00000077", "0?report") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/500-00000077", "1?Set(REALCALLERIDNUM=500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/500-00000077", "AMPUSER=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/500-00000077", "0?limit") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/500-00000077", "AMPUSERCIDNAME=????????? ???????") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/500-00000077", "0?report") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/500-00000077", "AMPUSERCID=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/500-00000077", "DIALOPTIONS=Ttr") "__DIAL_OPTIONS=Ttr") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/500-00000077", "CALLERID(all)="????????? ???????" <500>") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/500-00000077", "0?limit") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/500-00000077", "1?Set(GROUP(concurrencylimit)=500)") "1?Set(GROUP(concurrency_limit)=500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/500-00000077", "1?Set(CHANNEL(language)=en)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("SIP/500-00000077", "1?continue") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: pbx_builtins.c: Goto (macro-user-callerid,s,29) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:29] Set("SIP/500-00000077", "CALLERID(number)=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/500-00000077", "CALLERID(name)=????????? ???????") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/500-00000077", "CDR(cnum)=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/500-00000077", "CDR(cnam)=????????? ???????") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/500-00000077", "CHANNEL(language)=en") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:2] Gosub("SIP/500-00000077", "sub-record-check,s,1(out,89384848169,yes)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/500-00000077", "0?initialized") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:2] Set("SIP/500-00000077", "RECSTATUS=INITIALIZED") "__REC_STATUS=INITIALIZED") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:3] Set("SIP/500-00000077", "NOW=1470993657") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:4] Set("SIP/500-00000077", "DAY=12") "__DAY=12") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:5] Set("SIP/500-00000077", "MONTH=08") "__MONTH=08") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:6] Set("SIP/500-00000077", "YEAR=2016") "__YEAR=2016") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:7] Set("SIP/500-00000077", "TIMESTR=20160812-122057") "__TIMESTR=20160812-122057") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:8] Set("SIP/500-00000077", "FROMEXTEN=500") "__FROMEXTEN=500") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:9] Set("SIP/500-00000077", "MONFMT=WAV") "__MON_FMT=WAV") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/500-00000077", "Recordings initialized") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/500-00000077", "0?Set(ARG3=dontcare)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:12] Set("SIP/500-00000077", "RECPOLICYMODESAVE=") "REC_POLICY_MODE_SAVE=") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/500-00000077", "0?Set(RECSTATUS=NO)") "0?Set(REC_STATUS=NO)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/500-00000077", "3?checkaction") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: pbx_builtins.c: Goto (sub-record-check,s,17) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/500-00000077", "1?sub-record-check,out,1") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: pbx_builtins.c: Goto (sub-record-check,out,1) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/500-00000077", "Outbound Recording Check from 500 to 89384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:2] Set("SIP/500-00000077", "RECMODE=yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/500-00000077", "0?Goto(routewins)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:4] ExecIf("SIP/500-00000077", "0?Goto(routewins)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:5] Gosub("SIP/500-00000077", "recordcheck,1(yes,out,89384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/500-00000077", "Starting recording check against yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/500-00000077", "yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: pbx_builtins.c: Goto (sub-record-check,recordcheck,9) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("SIP/500-00000077", "0?Return()") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:10] Set("SIP/500-00000077", "RECPOLICYMODE=YES") "__REC_POLICY_MODE=YES") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("SIP/500-00000077", "startrec") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: pbx_builtins.c: Goto (sub-record-check,recordcheck,16) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/500-00000077", "Starting recording: out, 89384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:17] Set("SIP/500-00000077", "AUDIOHOOKINHERIT(MixMonitor)=yes") "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:18] Set("SIP/500-00000077", "CALLFILENAME=out-89384848169-500-20160812-122057-1470993657.119") "__CALLFILENAME=out-89384848169-500-20160812-122057-1470993657.119") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/500-00000077", "2016/08/12/out-89384848169-500-20160812-122057-1470993657.119.WAV,ai(LOCALMIXMONID),") "2016/08/12/out-89384848169-500-20160812-122057-1470993657.119.WAV,ai(LOCAL_MIXMON_ID),") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:20] Set("SIP/500-00000077", "MIXMONID=0xb224b4e0") "__MIXMON_ID=0xb224b4e0") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:21] Set("SIP/500-00000077", "RECORDID=SIP/500-00000077") "__RECORD_ID=SIP/500-00000077") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:22] Set("SIP/500-00000077", "RECSTATUS=RECORDING") "__REC_STATUS=RECORDING") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:23] Set("SIP/500-00000077", "CDR(recordingfile)=out-89384848169-500-20160812-122057-1470993657.119.WAV") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [recordcheck@sub-record-check:24] Return("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [out@sub-record-check:6] Return("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:3] ExecIf("SIP/500-00000077", "0 ?Set(CDR(accountcode)=)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:4] Set("SIP/500-00000077", "ROUTECIDSAVE="????????? "ROUTE_CIDSAVE="????????? ???????" <500>") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:5] Set("SIP/500-00000077", "EMERGENCYROUTE=YES") in new stack [2016-08-12 12:20:57] VERBOSE[30742][C-00000036] appmixmonitor.c: app_mixmonitor.c: Begin MixMonitor Recording SIP/500-00000077 [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:6] Set("SIP/500-00000077", "INTRACOMPANYROUTE=YES") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:7] Set("SIP/500-00000077", "MOHCLASS=default") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:8] ExecIf("SIP/500-00000077", "1?Set(TRUNKCIDOVERRIDE=79385450740)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:9] Set("SIP/500-00000077", "NODEST=") "_NODEST=") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [89384848169@from-internal:10] Macro("SIP/500-00000077", "dialout-trunk,7,79384848169,,off") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/500-00000077", "DIALTRUNK=7") "DIAL_TRUNK=7") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf("SIP/500-00000077", "0?sub-pincheck,s,1()") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf("SIP/500-00000077", "0?disabletrunk,1") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:4] Set("SIP/500-00000077", "DIALNUMBER=79384848169") "DIAL_NUMBER=79384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:5] Set("SIP/500-00000077", "DIALTRUNKOPTIONS=Ttr") "DIAL_TRUNK_OPTIONS=Ttr") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/500-00000077", "OUTBOUNDGROUP=OUT7") "OUTBOUND_GROUP=OUT_7") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("SIP/500-00000077", "1?nomax") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: pbx_builtins.c: Goto (macro-dialout-trunk,s,9) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("SIP/500-00000077", "1?skipoutcid") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbxbuiltins.c: pbx_builtins.c: Goto (macro-dialout-trunk,s,12) [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf("SIP/500-00000077", "1?sub-flp-7,s,1()") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:1] ExecIf("SIP/500-00000077", "0?Set(TARGETFLP7=786179384848169)") "0?Set(TARGET_FLP_7=786179384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:2] GotoIf("SIP/500-00000077", "0?match") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:3] ExecIf("SIP/500-00000077", "0?Set(TARGETFLP7=79384848169)") "0?Set(TARGET_FLP_7=79384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:4] GotoIf("SIP/500-00000077", "0?match") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@sub-flp-7:5] Return("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:13] Set("SIP/500-00000077", "OUTNUM=79384848169") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:14] Set("SIP/500-00000077", "custom=SIP/79385450740") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf("SIP/500-00000077", "0?Set(DIALTRUNKOPTIONS=M(setmusic^default)Ttr)") "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Ttr)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("SIP/500-00000077", "0?Set(DIALTRUNKOPTIONS=TtrM(confirm))") "0?Set(DIAL_TRUNK_OPTIONS=TtrM(confirm))") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:17] Macro("SIP/500-00000077", "dialout-trunk-predial-hook,") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/500-00000077", "") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf("SIP/500-00000077", "0?bypass,1") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf("SIP/500-00000077", "1?Set(CONNECTEDLINE(num,i)=79384848169)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("SIP/500-00000077", "1?Set(CONNECTEDLINE(name,i)=CID:500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("SIP/500-00000077", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)500)") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf("SIP/500-00000077", "0?customtrunk") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] pbx.c: Executing [s@macro-dialout-trunk:23] Dial("SIP/500-00000077", "SIP/79385450740/79384848169,300,Ttr") in new stack [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] netsock2.c: Using SIP RTP CoS mark 5 [2016-08-12 12:20:57] VERBOSE[30741][C-00000036] appdial.c: app_dial.c: Called SIP/79385450740/79384848169 [2016-08-12 12:20:58] WARNING[3723][C-00000036] chan_sip.c: Received response: "Forbidden" from '"????????? ???????" <sip:500@10.0.0.28>;tag=as40fe2082'<sip:500@10.0.0.28>;tag=as40fe2082'