[Показать] Спойлер:
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:1] Macro("PJSIP/24-000001b5", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/24-000001b5", "TOUCH_MONITOR=1545121240.1163") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/24-000001b5", "AMPUSER=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/24-000001b5", "0?report") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/24-000001b5", "1?Set(REALCALLERIDNUM=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/24-000001b5", "AMPUSER=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/24-000001b5", "0?limit") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/24-000001b5", "AMPUSERCIDNAME=Admin") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("PJSIP/24-000001b5", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("PJSIP/24-000001b5", "0?report") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/24-000001b5", "AMPUSERCID=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/24-000001b5", "__DIAL_OPTIONS=Ttr") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/24-000001b5", "CALLERID(all)="Admin" <24>") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/24-000001b5", "0?limit") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/24-000001b5", "1?Set(GROUP(concurrency_limit)=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("PJSIP/24-000001b5", "0?Set(CHANNEL(language)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:16] NoOp("PJSIP/24-000001b5", "Macro Depth is 1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("PJSIP/24-000001b5", "1?report2:macroerror") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("PJSIP/24-000001b5", "1?continue") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:37] Set("PJSIP/24-000001b5", "CALLERID(number)=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:38] Set("PJSIP/24-000001b5", "CALLERID(name)=Admin") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("PJSIP/24-000001b5", "0?cnum") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:40] Set("PJSIP/24-000001b5", "CDR(cnam)=Admin") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:41] Set("PJSIP/24-000001b5", "CDR(cnum)=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:42] Set("PJSIP/24-000001b5", "CHANNEL(language)=en") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:2] Gosub("PJSIP/24-000001b5", "sub-record-check,s,1(out,80256636565,yes)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/24-000001b5", "0?initialized") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/24-000001b5", "__REC_STATUS=INITIALIZED") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/24-000001b5", "NOW=1545121240") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/24-000001b5", "__DAY=18") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/24-000001b5", "__MONTH=12") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/24-000001b5", "__YEAR=2018") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/24-000001b5", "__TIMESTR=20181218-112040") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/24-000001b5", "__FROMEXTEN=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/24-000001b5", "__MON_FMT=wav") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/24-000001b5", "Recordings initialized") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/24-000001b5", "0?Set(ARG3=dontcare)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/24-000001b5", "REC_POLICY_MODE_SAVE=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/24-000001b5", "0?Set(REC_STATUS=NO)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/24-000001b5", "3?checkaction") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/24-000001b5", "1?sub-record-check,out,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/24-000001b5", "Outbound Recording Check from 24 to 80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/24-000001b5", "RECMODE=yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/24-000001b5", "0?Goto(routewins)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:4] ExecIf("PJSIP/24-000001b5", "0?Goto(routewins)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:5] Gosub("PJSIP/24-000001b5", "recordcheck,1(yes,out,80256636565)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/24-000001b5", "Starting recording check against yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/24-000001b5", "yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("PJSIP/24-000001b5", "0?Return()") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:10] Set("PJSIP/24-000001b5", "__REC_POLICY_MODE=YES") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("PJSIP/24-000001b5", "startrec") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("PJSIP/24-000001b5", "Starting recording: out, 80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:17] Set("PJSIP/24-000001b5", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:18] Set("PJSIP/24-000001b5", "__CALLFILENAME=out-80256636565-24-20181218-112040-1545121240.1163") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor("PJSIP/24-000001b5", "2018/12/18/out-80256636565-24-20181218-112040-1545121240.1163.wav,abi(LOCAL_MIXMON_ID),") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:20] Set("PJSIP/24-000001b5", "__MIXMON_ID=0x7f0f3c071750") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:21] Set("PJSIP/24-000001b5", "__RECORD_ID=PJSIP/24-000001b5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:22] Set("PJSIP/24-000001b5", "__REC_STATUS=RECORDING") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:23] Set("PJSIP/24-000001b5", "CDR(recordingfile)=out-80256636565-24-20181218-112040-1545121240.1163.wav") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:24] Return("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:6] Return("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:3] ExecIf("PJSIP/24-000001b5", "0 ?Set(CDR(accountcode)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:4] Set("PJSIP/24-000001b5", "MOHCLASS=default") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:5] Set("PJSIP/24-000001b5", "_NODEST=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:6] Macro("PJSIP/24-000001b5", "dialout-trunk,5,80256636565,,off") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/24-000001b5", "DIAL_TRUNK=5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/24-000001b5", "0?Set(DIAL_OPTIONS=tr)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/24-000001b5", "0?sub-pincheck,s,1()") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:4] GotoIf("PJSIP/24-000001b5", "0?disabletrunk,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:5] Set("PJSIP/24-000001b5", "DIAL_NUMBER=80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/24-000001b5", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/24-000001b5", "OUTBOUND_GROUP=OUT_5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/24-000001b5", "DIAL_TRUNK_OPTIONS=T") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("PJSIP/24-000001b5", "1?nomax") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,s,11)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/24-000001b5", "0?skipoutcid") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:12] Macro("PJSIP/24-000001b5", "outbound-callerid,5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf("PJSIP/24-000001b5", "0?Set(REALCALLERIDNUM=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/24-000001b5", "0?Set(AMPUSER=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:5] GotoIf("PJSIP/24-000001b5", "1?normcid") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-outbound-callerid,s,9)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:9] Set("PJSIP/24-000001b5", "USEROUTCID=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:10] Set("PJSIP/24-000001b5", "EMERGENCYCID=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/24-000001b5", "TRUNKOUTCID=79307328158") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:12] GotoIf("PJSIP/24-000001b5", "1?trunkcid") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-outbound-callerid,s,17)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf("PJSIP/24-000001b5", "1?Set(CALLERID(all)=79307328158)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERID(all)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERID(all)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:22] Set("PJSIP/24-000001b5", "CDR(outbound_cnum)=79307328158") in new stack
[2018-12-18 11:20:40] VERBOSE[28532][C-00000133] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/24-000001b5
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/24-000001b5", "CDR(outbound_cnam)=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:13] GosubIf("PJSIP/24-000001b5", "0?sub-flp-5,s,1()") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:14] Set("PJSIP/24-000001b5", "OUTNUM=80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:15] Set("PJSIP/24-000001b5", "custom=SIP/multifon") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("PJSIP/24-000001b5", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/24-000001b5", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:18] Macro("PJSIP/24-000001b5", "dialout-trunk-predial-hook,") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf("PJSIP/24-000001b5", "0?bypass,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/24-000001b5", "1?Set(CONNECTEDLINE(num,i)=80256636565)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/24-000001b5", "1?Set(CONNECTEDLINE(name,i)=CID:79307328158)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/24-000001b5", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)79307328158)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:23] GotoIf("PJSIP/24-000001b5", "0?customtrunk") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:24] Dial("PJSIP/24-000001b5", "SIP/multifon/80256636565,300,T") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] netsock2.c: Using SIP RTP TOS bits 184
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] netsock2.c: Using SIP RTP CoS mark 5
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] app_dial.c: Called SIP/multifon/80256636565
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:25] NoOp("PJSIP/24-000001b5", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 58") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:26] GotoIf("PJSIP/24-000001b5", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("PJSIP/24-000001b5", "RC=58") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("PJSIP/24-000001b5", "58,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,58,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [58@macro-dialout-trunk:1] Goto("PJSIP/24-000001b5", "continue,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/24-000001b5", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 58 - failing through to other trunks") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/24-000001b5", "1?Set(CALLERID(number)=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:7] Macro("PJSIP/24-000001b5", "outisbusy,") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/24-000001b5", "0?emergency,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/24-000001b5", "0?intracompany,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/24-000001b5", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] file.c: <PJSIP/24-000001b5> Playing 'all-circuits-busy-now.alaw' (language 'en')
[2018-12-18 11:20:42] WARNING[28529][C-00000133] file.c: File please-try-call-later does not exist in any format
[2018-12-18 11:20:42] WARNING[28529][C-00000133] file.c: Unable to open please-try-call-later (format (alaw)): No such file or directory
[2018-12-18 11:20:42] WARNING[28529][C-00000133] app_playback.c: Playback failed on PJSIP/24-000001b5 for all-circuits-busy-now&please-try-call-later, noanswer
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:5] Congestion("PJSIP/24-000001b5", "20") in new stack
[2018-12-18 11:20:42] WARNING[28529][C-00000133] translate.c: no samples for alawtolin
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'PJSIP/24-000001b5' in macro 'outisbusy'
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Spawn extension (from-internal, 80256636565, 7) exited non-zero on 'PJSIP/24-000001b5'
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/24-000001b5", "hangupcall") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/24-000001b5", "1?theend") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/24-000001b5", "0?Set(CDR(recordingfile)=)") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/24-000001b5' in macro 'hangupcall'
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/24-000001b5'
[2018-12-18 11:20:42] VERBOSE[28532][C-00000133] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2018-12-18 11:20:42] VERBOSE[28532][C-00000133] app_mixmonitor.c: End MixMonitor Recording PJSIP/24-000001b5
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/24-000001b5", "TOUCH_MONITOR=1545121240.1163") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/24-000001b5", "AMPUSER=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/24-000001b5", "0?report") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/24-000001b5", "1?Set(REALCALLERIDNUM=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/24-000001b5", "AMPUSER=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/24-000001b5", "0?limit") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/24-000001b5", "AMPUSERCIDNAME=Admin") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("PJSIP/24-000001b5", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("PJSIP/24-000001b5", "0?report") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/24-000001b5", "AMPUSERCID=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/24-000001b5", "__DIAL_OPTIONS=Ttr") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/24-000001b5", "CALLERID(all)="Admin" <24>") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/24-000001b5", "0?limit") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/24-000001b5", "1?Set(GROUP(concurrency_limit)=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("PJSIP/24-000001b5", "0?Set(CHANNEL(language)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:16] NoOp("PJSIP/24-000001b5", "Macro Depth is 1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("PJSIP/24-000001b5", "1?report2:macroerror") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("PJSIP/24-000001b5", "1?continue") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:37] Set("PJSIP/24-000001b5", "CALLERID(number)=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:38] Set("PJSIP/24-000001b5", "CALLERID(name)=Admin") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("PJSIP/24-000001b5", "0?cnum") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:40] Set("PJSIP/24-000001b5", "CDR(cnam)=Admin") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:41] Set("PJSIP/24-000001b5", "CDR(cnum)=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-user-callerid:42] Set("PJSIP/24-000001b5", "CHANNEL(language)=en") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:2] Gosub("PJSIP/24-000001b5", "sub-record-check,s,1(out,80256636565,yes)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/24-000001b5", "0?initialized") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/24-000001b5", "__REC_STATUS=INITIALIZED") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/24-000001b5", "NOW=1545121240") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/24-000001b5", "__DAY=18") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/24-000001b5", "__MONTH=12") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/24-000001b5", "__YEAR=2018") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/24-000001b5", "__TIMESTR=20181218-112040") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/24-000001b5", "__FROMEXTEN=24") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/24-000001b5", "__MON_FMT=wav") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/24-000001b5", "Recordings initialized") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/24-000001b5", "0?Set(ARG3=dontcare)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/24-000001b5", "REC_POLICY_MODE_SAVE=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/24-000001b5", "0?Set(REC_STATUS=NO)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/24-000001b5", "3?checkaction") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/24-000001b5", "1?sub-record-check,out,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/24-000001b5", "Outbound Recording Check from 24 to 80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/24-000001b5", "RECMODE=yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/24-000001b5", "0?Goto(routewins)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:4] ExecIf("PJSIP/24-000001b5", "0?Goto(routewins)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:5] Gosub("PJSIP/24-000001b5", "recordcheck,1(yes,out,80256636565)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/24-000001b5", "Starting recording check against yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/24-000001b5", "yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("PJSIP/24-000001b5", "0?Return()") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:10] Set("PJSIP/24-000001b5", "__REC_POLICY_MODE=YES") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("PJSIP/24-000001b5", "startrec") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("PJSIP/24-000001b5", "Starting recording: out, 80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:17] Set("PJSIP/24-000001b5", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:18] Set("PJSIP/24-000001b5", "__CALLFILENAME=out-80256636565-24-20181218-112040-1545121240.1163") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor("PJSIP/24-000001b5", "2018/12/18/out-80256636565-24-20181218-112040-1545121240.1163.wav,abi(LOCAL_MIXMON_ID),") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:20] Set("PJSIP/24-000001b5", "__MIXMON_ID=0x7f0f3c071750") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:21] Set("PJSIP/24-000001b5", "__RECORD_ID=PJSIP/24-000001b5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:22] Set("PJSIP/24-000001b5", "__REC_STATUS=RECORDING") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:23] Set("PJSIP/24-000001b5", "CDR(recordingfile)=out-80256636565-24-20181218-112040-1545121240.1163.wav") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [recordcheck@sub-record-check:24] Return("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [out@sub-record-check:6] Return("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:3] ExecIf("PJSIP/24-000001b5", "0 ?Set(CDR(accountcode)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:4] Set("PJSIP/24-000001b5", "MOHCLASS=default") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:5] Set("PJSIP/24-000001b5", "_NODEST=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:6] Macro("PJSIP/24-000001b5", "dialout-trunk,5,80256636565,,off") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/24-000001b5", "DIAL_TRUNK=5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/24-000001b5", "0?Set(DIAL_OPTIONS=tr)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/24-000001b5", "0?sub-pincheck,s,1()") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:4] GotoIf("PJSIP/24-000001b5", "0?disabletrunk,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:5] Set("PJSIP/24-000001b5", "DIAL_NUMBER=80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/24-000001b5", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/24-000001b5", "OUTBOUND_GROUP=OUT_5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/24-000001b5", "DIAL_TRUNK_OPTIONS=T") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("PJSIP/24-000001b5", "1?nomax") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,s,11)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/24-000001b5", "0?skipoutcid") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:12] Macro("PJSIP/24-000001b5", "outbound-callerid,5") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf("PJSIP/24-000001b5", "0?Set(REALCALLERIDNUM=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/24-000001b5", "0?Set(AMPUSER=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:5] GotoIf("PJSIP/24-000001b5", "1?normcid") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-outbound-callerid,s,9)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:9] Set("PJSIP/24-000001b5", "USEROUTCID=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:10] Set("PJSIP/24-000001b5", "EMERGENCYCID=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/24-000001b5", "TRUNKOUTCID=79307328158") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:12] GotoIf("PJSIP/24-000001b5", "1?trunkcid") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-outbound-callerid,s,17)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf("PJSIP/24-000001b5", "1?Set(CALLERID(all)=79307328158)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERID(all)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERID(all)=)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("PJSIP/24-000001b5", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:22] Set("PJSIP/24-000001b5", "CDR(outbound_cnum)=79307328158") in new stack
[2018-12-18 11:20:40] VERBOSE[28532][C-00000133] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/24-000001b5
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/24-000001b5", "CDR(outbound_cnam)=") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:13] GosubIf("PJSIP/24-000001b5", "0?sub-flp-5,s,1()") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:14] Set("PJSIP/24-000001b5", "OUTNUM=80256636565") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:15] Set("PJSIP/24-000001b5", "custom=SIP/multifon") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("PJSIP/24-000001b5", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/24-000001b5", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:18] Macro("PJSIP/24-000001b5", "dialout-trunk-predial-hook,") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf("PJSIP/24-000001b5", "0?bypass,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/24-000001b5", "1?Set(CONNECTEDLINE(num,i)=80256636565)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/24-000001b5", "1?Set(CONNECTEDLINE(name,i)=CID:79307328158)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/24-000001b5", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)79307328158)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:23] GotoIf("PJSIP/24-000001b5", "0?customtrunk") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:24] Dial("PJSIP/24-000001b5", "SIP/multifon/80256636565,300,T") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] netsock2.c: Using SIP RTP TOS bits 184
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] netsock2.c: Using SIP RTP CoS mark 5
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] app_dial.c: Called SIP/multifon/80256636565
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:25] NoOp("PJSIP/24-000001b5", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 58") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-dialout-trunk:26] GotoIf("PJSIP/24-000001b5", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("PJSIP/24-000001b5", "RC=58") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("PJSIP/24-000001b5", "58,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,58,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [58@macro-dialout-trunk:1] Goto("PJSIP/24-000001b5", "continue,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/24-000001b5", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 58 - failing through to other trunks") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/24-000001b5", "1?Set(CALLERID(number)=24)") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [80256636565@from-internal:7] Macro("PJSIP/24-000001b5", "outisbusy,") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/24-000001b5", "0?emergency,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/24-000001b5", "0?intracompany,1") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/24-000001b5", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
[2018-12-18 11:20:40] VERBOSE[28529][C-00000133] file.c: <PJSIP/24-000001b5> Playing 'all-circuits-busy-now.alaw' (language 'en')
[2018-12-18 11:20:42] WARNING[28529][C-00000133] file.c: File please-try-call-later does not exist in any format
[2018-12-18 11:20:42] WARNING[28529][C-00000133] file.c: Unable to open please-try-call-later (format (alaw)): No such file or directory
[2018-12-18 11:20:42] WARNING[28529][C-00000133] app_playback.c: Playback failed on PJSIP/24-000001b5 for all-circuits-busy-now&please-try-call-later, noanswer
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-outisbusy:5] Congestion("PJSIP/24-000001b5", "20") in new stack
[2018-12-18 11:20:42] WARNING[28529][C-00000133] translate.c: no samples for alawtolin
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'PJSIP/24-000001b5' in macro 'outisbusy'
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Spawn extension (from-internal, 80256636565, 7) exited non-zero on 'PJSIP/24-000001b5'
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/24-000001b5", "hangupcall") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/24-000001b5", "1?theend") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/24-000001b5", "0?Set(CDR(recordingfile)=)") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/24-000001b5", "") in new stack
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/24-000001b5' in macro 'hangupcall'
[2018-12-18 11:20:42] VERBOSE[28529][C-00000133] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/24-000001b5'
[2018-12-18 11:20:42] VERBOSE[28532][C-00000133] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2018-12-18 11:20:42] VERBOSE[28532][C-00000133] app_mixmonitor.c: End MixMonitor Recording PJSIP/24-000001b5
