[2024-11-09 00:21:09] VERBOSE[10837] dial.c: Called 6475106450@webcallback-1
[2024-11-09 00:21:09] VERBOSE[10838][C-0000002c] pbx.c: Executing [6475106450@webcallback-1:1] NoOp("Local/6475106450@webcallback-1-0000001c;2", "WEBCALLBACK call from ") in new stack
[2024-11-09 00:21:09] VERBOSE[10838][C-0000002c] pbx.c: Executing [6475106450@webcallback-1:2] Dial("Local/6475106450@webcallback-1-0000001c;2", "Local/916475106450@from-internal,,tr") in new stack
[2024-11-09 00:21:09] VERBOSE[10838][C-0000002c] app_dial.c: Called Local/916475106450@from-internal
[2024-11-09 00:21:09] VERBOSE[10837] dial.c: Local/6475106450@webcallback-1-0000001c;1 is ringing
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:1] Macro("Local/916475106450@from-internal-0000001d;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/916475106450@from-internal-0000001d;2", "TOUCH_MONITOR=1731111669.102") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/916475106450@from-internal-0000001d;2", "CHANCONTEXT=from-internal-0000001d;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:3] Progress("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/916475106450@from-internal-0000001d;2", "CHANCONTEXT=from") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/916475106450@from-internal-0000001d;2", "CHANEXTENCONTEXT=916475106450@from-internal-0000001d;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/916475106450@from-internal-0000001d;2", "CHANEXTEN=916475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/916475106450@from-internal-0000001d;2", "CALLERID(number)=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/916475106450@from-internal-0000001d;2", "AMPUSER=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/916475106450@from-internal-0000001d;2", "HOTDESCKCHAN=916475106450@from-internal-0000001d;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/916475106450@from-internal-0000001d;2", "HOTDESKEXTEN=916475106450@from") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:11] Set("Local/916475106450@from-internal-0000001d;2", "HOTDESKCALL=0") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?report") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("Local/916475106450@from-internal-0000001d;2", "1?Set(REALCALLERIDNUM=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:16] Set("Local/916475106450@from-internal-0000001d;2", "AMPUSER=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?limit") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:18] Set("Local/916475106450@from-internal-0000001d;2", "AMPUSERCIDNAME=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?report") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:29] NoOp("Local/916475106450@from-internal-0000001d;2", "Macro Depth is 1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?report2:macroerror") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (macro-user-callerid,s,31)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?continue") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (macro-user-callerid,s,50)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/916475106450@from-internal-0000001d;2", "CALLERID(number)=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:51] Set("Local/916475106450@from-internal-0000001d;2", "CALLERID(name)=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?cnum") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:53] Set("Local/916475106450@from-internal-0000001d;2", "__MCNUM=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:54] Set("Local/916475106450@from-internal-0000001d;2", "__MCNAME=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:55] Set("Local/916475106450@from-internal-0000001d;2", "__MCEXTEN=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:56] Set("Local/916475106450@from-internal-0000001d;2", "__MCORGCHAN=Local/916475106450@from-internal-0000001d;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:57] Set("Local/916475106450@from-internal-0000001d;2", "CDR(cnam)=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:58] Set("Local/916475106450@from-internal-0000001d;2", "CDR(cnum)=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-user-callerid:59] Set("Local/916475106450@from-internal-0000001d;2", "CHANNEL(language)=en") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:2] Gosub("Local/916475106450@from-internal-0000001d;2", "sub-record-check,s,1(out,916475106450,dontcare)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?initialized") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:2] Set("Local/916475106450@from-internal-0000001d;2", "__REC_STATUS=INITIALIZED") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:3] Set("Local/916475106450@from-internal-0000001d;2", "NOW=1731111669") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:4] Set("Local/916475106450@from-internal-0000001d;2", "__DAY=09") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:5] Set("Local/916475106450@from-internal-0000001d;2", "__MONTH=11") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:6] Set("Local/916475106450@from-internal-0000001d;2", "__YEAR=2024") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:7] Set("Local/916475106450@from-internal-0000001d;2", "__TIMESTR=20241109-002109") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:8] Set("Local/916475106450@from-internal-0000001d;2", "__FROMEXTEN=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:9] Set("Local/916475106450@from-internal-0000001d;2", "__MON_FMT=wav") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/916475106450@from-internal-0000001d;2", "Recordings initialized") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(ARG3=dontcare)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:12] Set("Local/916475106450@from-internal-0000001d;2", "REC_POLICY_MODE_SAVE=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/916475106450@from-internal-0000001d;2", "3?checkaction") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?sub-record-check,out,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (sub-record-check,out,1)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [out@sub-record-check:1] NoOp("Local/916475106450@from-internal-0000001d;2", "Outbound Recording Check from 6475106450 to 916475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [out@sub-record-check:2] Set("Local/916475106450@from-internal-0000001d;2", "RECMODE=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [out@sub-record-check:3] ExecIf("Local/916475106450@from-internal-0000001d;2", "1?Goto(routewins)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (sub-record-check,out,7)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [out@sub-record-check:7] Gosub("Local/916475106450@from-internal-0000001d;2", "recordcheck,1(dontcare,out,916475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/916475106450@from-internal-0000001d;2", "Starting recording check against dontcare") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/916475106450@from-internal-0000001d;2", "dontcare") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [out@sub-record-check:8] Return("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:3] ExecIf("Local/916475106450@from-internal-0000001d;2", "0 ?Set(CDR(accountcode)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:4] Set("Local/916475106450@from-internal-0000001d;2", "_ROUTEID=1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:5] Set("Local/916475106450@from-internal-0000001d;2", "_ROUTENAME=VoIP MS Out Bound Route") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:6] Set("Local/916475106450@from-internal-0000001d;2", "MOHCLASS=default") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:7] ExecIf("Local/916475106450@from-internal-0000001d;2", "1?Set(TRUNKCIDOVERRIDE=6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:8] Set("Local/916475106450@from-internal-0000001d;2", "_CALLERIDNAMEINTERNAL=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:9] Set("Local/916475106450@from-internal-0000001d;2", "_CALLERIDNUMINTERNAL=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:10] Set("Local/916475106450@from-internal-0000001d;2", "_EMAILNOTIFICATION=FALSE") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:11] Set("Local/916475106450@from-internal-0000001d;2", "_NODEST=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [916475106450@from-internal:12] Macro("Local/916475106450@from-internal-0000001d;2", "dialout-trunk,3,16475106450,,off") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:1] Set("Local/916475106450@from-internal-0000001d;2", "DIAL_TRUNK=3") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(DIAL_OPTIONS=HhTr)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:5] GosubIf("Local/916475106450@from-internal-0000001d;2", "0?sub-pincheck,s,1()") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:6] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(num)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?disabletrunk,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:8] Set("Local/916475106450@from-internal-0000001d;2", "DIAL_NUMBER=16475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:9] Set("Local/916475106450@from-internal-0000001d;2", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:10] Set("Local/916475106450@from-internal-0000001d;2", "OUTBOUND_GROUP=OUT_3") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:11] Set("Local/916475106450@from-internal-0000001d;2", "DIAL_TRUNK_OPTIONS=T") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:12] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?nomax") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (macro-dialout-trunk,s,15)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:15] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?skipoutcid") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:16] Macro("Local/916475106450@from-internal-0000001d;2", "outbound-callerid,3") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("Local/916475106450@from-internal-0000001d;2", "6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("Local/916475106450@from-internal-0000001d;2", "off") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(name-pres)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(num-pres)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:6] Set("Local/916475106450@from-internal-0000001d;2", "HOTDESCKCHAN=916475106450@from-internal-0000001d;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:7] Set("Local/916475106450@from-internal-0000001d;2", "HOTDESKEXTEN=916475106450@from") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:8] Set("Local/916475106450@from-internal-0000001d;2", "HOTDESKCALL=0") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:11] Set("Local/916475106450@from-internal-0000001d;2", "ALLOWTHISROUTE=NO") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(ALLOWTHISROUTE=YES)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Hangup()") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(REALCALLERIDNUM=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(AMPUSER=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?normcid") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:20] Set("Local/916475106450@from-internal-0000001d;2", "USEROUTCID=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:21] Set("Local/916475106450@from-internal-0000001d;2", "EMERGENCYCID=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(SCDEXT=YES)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(EMERGENCYCID=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(EMERGENCYCID=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:26] Set("Local/916475106450@from-internal-0000001d;2", "TRUNKOUTCID=6474784908") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:27] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?trunkcid") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (macro-outbound-callerid,s,33)
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("Local/916475106450@from-internal-0000001d;2", "1?Set(CALLERID(all)=6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(all)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("Local/916475106450@from-internal-0000001d;2", "1?Set(CALLERID(all)=6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(all)=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(all)=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:38] Set("Local/916475106450@from-internal-0000001d;2", "TIOHIDE=no") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:40] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:41] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:42] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:43] Set("Local/916475106450@from-internal-0000001d;2", "CDR(outbound_cnum)=6474784908") in new stack
[2024-11-09 00:21:09] VERBOSE[10838][C-0000002c] app_dial.c: Local/916475106450@from-internal-0000001d;1 is making progress passing it to Local/6475106450@webcallback-1-0000001c;2
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-outbound-callerid:44] Set("Local/916475106450@from-internal-0000001d;2", "CDR(outbound_cnam)=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:17] GosubIf("Local/916475106450@from-internal-0000001d;2", "0?sub-flp-3,s,1()") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:18] Set("Local/916475106450@from-internal-0000001d;2", "OUTNUM=16475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:19] Set("Local/916475106450@from-internal-0000001d;2", "custom=PJSIP") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?AGI(allowlist-autoadd.agi,)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:23] Macro("Local/916475106450@from-internal-0000001d;2", "dialout-trunk-predial-hook,") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?skipcrm") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:25] Set("Local/916475106450@from-internal-0000001d;2", "__CRM_DIRECTION=OUTBOUND") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:26] Set("Local/916475106450@from-internal-0000001d;2", "__CRM_DESTINATION=16475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:27] Set("Local/916475106450@from-internal-0000001d;2", "__CRM_SOURCE=") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:28] AGI("Local/916475106450@from-internal-0000001d;2", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] res_agi.c: <Local/916475106450@from-internal-0000001d;2>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:29] Set("Local/916475106450@from-internal-0000001d;2", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:30] NoOp("Local/916475106450@from-internal-0000001d;2", "CRM Finished") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?bypass,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CONNECTEDLINE(num,i)=16475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:33] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CONNECTEDLINE(name,i)=CID:6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:34] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:35] GotoIf("Local/916475106450@from-internal-0000001d;2", "0?customtrunk") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:36] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:37] Set("Local/916475106450@from-internal-0000001d;2", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-dialout-trunk:38] Dial("Local/916475106450@from-internal-0000001d;2", "PJSIP/16475106450@VoIP.ms,300,Tb(func-apply-sipheaders^s^1,(3))U(sub-send-obroute-email^16475106450^916475106450^3^1731111669^^6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] app_stack.c: PJSIP/VoIP.ms-0000002b Internal Gosub(func-apply-sipheaders,s,1(3)) start
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/VoIP.ms-0000002b", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/VoIP.ms-0000002b", "Applying SIP Headers to channel PJSIP/VoIP.ms-0000002b") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/VoIP.ms-0000002b", "localchan=VoIP.ms-0000002b") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/VoIP.ms-0000002b", "DialMCEXT=VoIP.ms") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/VoIP.ms-0000002b", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,VoIP.ms,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/VoIP.ms-0000002b", "TECH=PJSIP") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/VoIP.ms-0000002b", "SIPHEADERKEYS=Alert-Info") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/VoIP.ms-0000002b", "1") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:9] Set("PJSIP/VoIP.ms-0000002b", "sipheader=unset") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/VoIP.ms-0000002b", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2024-11-09 00:21:09] ERROR[13750] res_pjsip_header_funcs.c: No headers had been previously added to this session.
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/VoIP.ms-0000002b", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/VoIP.ms-0000002b", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/VoIP.ms-0000002b", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:14] EndWhile("PJSIP/VoIP.ms-0000002b", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/VoIP.ms-0000002b", "0") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:15] Return("PJSIP/VoIP.ms-0000002b", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] app_stack.c: Spawn extension (func-apply-sipheaders, s, 15) exited non-zero on 'PJSIP/VoIP.ms-0000002b'
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] app_stack.c: PJSIP/VoIP.ms-0000002b Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
[2024-11-09 00:21:09] VERBOSE[10839][C-0000002c] app_dial.c: Called PJSIP/16475106450@VoIP.ms
[2024-11-09 00:21:09] VERBOSE[10857] dial.c: Called 6475106450@webcallback-1
[2024-11-09 00:21:09] VERBOSE[10858][C-0000002d] pbx.c: Executing [6475106450@webcallback-1:1] NoOp("Local/6475106450@webcallback-1-0000001e;2", "WEBCALLBACK call from ") in new stack
[2024-11-09 00:21:09] VERBOSE[10858][C-0000002d] pbx.c: Executing [6475106450@webcallback-1:2] Dial("Local/6475106450@webcallback-1-0000001e;2", "Local/916475106450@from-internal,,tr") in new stack
[2024-11-09 00:21:09] VERBOSE[10858][C-0000002d] app_dial.c: Called Local/916475106450@from-internal
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:1] Macro("Local/916475106450@from-internal-0000001f;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/916475106450@from-internal-0000001f;2", "TOUCH_MONITOR=1731111669.107") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/916475106450@from-internal-0000001f;2", "CHANCONTEXT=from-internal-0000001f;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:3] Progress("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/916475106450@from-internal-0000001f;2", "CHANCONTEXT=from") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/916475106450@from-internal-0000001f;2", "CHANEXTENCONTEXT=916475106450@from-internal-0000001f;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/916475106450@from-internal-0000001f;2", "CHANEXTEN=916475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/916475106450@from-internal-0000001f;2", "CALLERID(number)=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/916475106450@from-internal-0000001f;2", "AMPUSER=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/916475106450@from-internal-0000001f;2", "HOTDESCKCHAN=916475106450@from-internal-0000001f;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/916475106450@from-internal-0000001f;2", "HOTDESKEXTEN=916475106450@from") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:11] Set("Local/916475106450@from-internal-0000001f;2", "HOTDESKCALL=0") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?report") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("Local/916475106450@from-internal-0000001f;2", "1?Set(REALCALLERIDNUM=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:16] Set("Local/916475106450@from-internal-0000001f;2", "AMPUSER=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?limit") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:18] Set("Local/916475106450@from-internal-0000001f;2", "AMPUSERCIDNAME=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?report") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:29] NoOp("Local/916475106450@from-internal-0000001f;2", "Macro Depth is 1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?report2:macroerror") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (macro-user-callerid,s,31)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?continue") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (macro-user-callerid,s,50)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/916475106450@from-internal-0000001f;2", "CALLERID(number)=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:51] Set("Local/916475106450@from-internal-0000001f;2", "CALLERID(name)=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?cnum") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:53] Set("Local/916475106450@from-internal-0000001f;2", "__MCNUM=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:54] Set("Local/916475106450@from-internal-0000001f;2", "__MCNAME=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:55] Set("Local/916475106450@from-internal-0000001f;2", "__MCEXTEN=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:56] Set("Local/916475106450@from-internal-0000001f;2", "__MCORGCHAN=Local/916475106450@from-internal-0000001f;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:57] Set("Local/916475106450@from-internal-0000001f;2", "CDR(cnam)=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10858][C-0000002d] app_dial.c: Local/916475106450@from-internal-0000001f;1 is making progress passing it to Local/6475106450@webcallback-1-0000001e;2
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:58] Set("Local/916475106450@from-internal-0000001f;2", "CDR(cnum)=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-user-callerid:59] Set("Local/916475106450@from-internal-0000001f;2", "CHANNEL(language)=en") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:2] Gosub("Local/916475106450@from-internal-0000001f;2", "sub-record-check,s,1(out,916475106450,dontcare)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?initialized") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:2] Set("Local/916475106450@from-internal-0000001f;2", "__REC_STATUS=INITIALIZED") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:3] Set("Local/916475106450@from-internal-0000001f;2", "NOW=1731111669") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:4] Set("Local/916475106450@from-internal-0000001f;2", "__DAY=09") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:5] Set("Local/916475106450@from-internal-0000001f;2", "__MONTH=11") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:6] Set("Local/916475106450@from-internal-0000001f;2", "__YEAR=2024") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:7] Set("Local/916475106450@from-internal-0000001f;2", "__TIMESTR=20241109-002109") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:8] Set("Local/916475106450@from-internal-0000001f;2", "__FROMEXTEN=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:9] Set("Local/916475106450@from-internal-0000001f;2", "__MON_FMT=wav") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/916475106450@from-internal-0000001f;2", "Recordings initialized") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(ARG3=dontcare)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:12] Set("Local/916475106450@from-internal-0000001f;2", "REC_POLICY_MODE_SAVE=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/916475106450@from-internal-0000001f;2", "3?checkaction") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?sub-record-check,out,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (sub-record-check,out,1)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [out@sub-record-check:1] NoOp("Local/916475106450@from-internal-0000001f;2", "Outbound Recording Check from 6475106450 to 916475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [out@sub-record-check:2] Set("Local/916475106450@from-internal-0000001f;2", "RECMODE=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [out@sub-record-check:3] ExecIf("Local/916475106450@from-internal-0000001f;2", "1?Goto(routewins)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (sub-record-check,out,7)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [out@sub-record-check:7] Gosub("Local/916475106450@from-internal-0000001f;2", "recordcheck,1(dontcare,out,916475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/916475106450@from-internal-0000001f;2", "Starting recording check against dontcare") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/916475106450@from-internal-0000001f;2", "dontcare") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [out@sub-record-check:8] Return("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:3] ExecIf("Local/916475106450@from-internal-0000001f;2", "0 ?Set(CDR(accountcode)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:4] Set("Local/916475106450@from-internal-0000001f;2", "_ROUTEID=1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:5] Set("Local/916475106450@from-internal-0000001f;2", "_ROUTENAME=VoIP MS Out Bound Route") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:6] Set("Local/916475106450@from-internal-0000001f;2", "MOHCLASS=default") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:7] ExecIf("Local/916475106450@from-internal-0000001f;2", "1?Set(TRUNKCIDOVERRIDE=6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:8] Set("Local/916475106450@from-internal-0000001f;2", "_CALLERIDNAMEINTERNAL=Blue Marble") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:9] Set("Local/916475106450@from-internal-0000001f;2", "_CALLERIDNUMINTERNAL=6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:10] Set("Local/916475106450@from-internal-0000001f;2", "_EMAILNOTIFICATION=FALSE") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:11] Set("Local/916475106450@from-internal-0000001f;2", "_NODEST=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [916475106450@from-internal:12] Macro("Local/916475106450@from-internal-0000001f;2", "dialout-trunk,3,16475106450,,off") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:1] Set("Local/916475106450@from-internal-0000001f;2", "DIAL_TRUNK=3") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(DIAL_OPTIONS=HhTr)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:5] GosubIf("Local/916475106450@from-internal-0000001f;2", "0?sub-pincheck,s,1()") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:6] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(num)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?disabletrunk,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:8] Set("Local/916475106450@from-internal-0000001f;2", "DIAL_NUMBER=16475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:9] Set("Local/916475106450@from-internal-0000001f;2", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:10] Set("Local/916475106450@from-internal-0000001f;2", "OUTBOUND_GROUP=OUT_3") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:11] Set("Local/916475106450@from-internal-0000001f;2", "DIAL_TRUNK_OPTIONS=T") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:12] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?nomax") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (macro-dialout-trunk,s,15)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:15] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?skipoutcid") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:16] Macro("Local/916475106450@from-internal-0000001f;2", "outbound-callerid,3") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("Local/916475106450@from-internal-0000001f;2", "6475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("Local/916475106450@from-internal-0000001f;2", "off") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(name-pres)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(num-pres)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:6] Set("Local/916475106450@from-internal-0000001f;2", "HOTDESCKCHAN=916475106450@from-internal-0000001f;2") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:7] Set("Local/916475106450@from-internal-0000001f;2", "HOTDESKEXTEN=916475106450@from") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:8] Set("Local/916475106450@from-internal-0000001f;2", "HOTDESKCALL=0") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:11] Set("Local/916475106450@from-internal-0000001f;2", "ALLOWTHISROUTE=NO") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(ALLOWTHISROUTE=YES)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Hangup()") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(REALCALLERIDNUM=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(AMPUSER=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?normcid") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:20] Set("Local/916475106450@from-internal-0000001f;2", "USEROUTCID=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:21] Set("Local/916475106450@from-internal-0000001f;2", "EMERGENCYCID=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(SCDEXT=YES)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(EMERGENCYCID=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(EMERGENCYCID=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:26] Set("Local/916475106450@from-internal-0000001f;2", "TRUNKOUTCID=6474784908") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:27] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?trunkcid") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (macro-outbound-callerid,s,33)
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("Local/916475106450@from-internal-0000001f;2", "1?Set(CALLERID(all)=6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(all)=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("Local/916475106450@from-internal-0000001f;2", "1?Set(CALLERID(all)=6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(all)=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(all)=6475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:38] Set("Local/916475106450@from-internal-0000001f;2", "TIOHIDE=no") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:40] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:41] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:42] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:43] Set("Local/916475106450@from-internal-0000001f;2", "CDR(outbound_cnum)=6474784908") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-outbound-callerid:44] Set("Local/916475106450@from-internal-0000001f;2", "CDR(outbound_cnam)=") in new stack
[2024-11-09 00:21:09] VERBOSE[10857] dial.c: Local/6475106450@webcallback-1-0000001e;1 is ringing
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:17] GosubIf("Local/916475106450@from-internal-0000001f;2", "0?sub-flp-3,s,1()") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:18] Set("Local/916475106450@from-internal-0000001f;2", "OUTNUM=16475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:19] Set("Local/916475106450@from-internal-0000001f;2", "custom=PJSIP") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?AGI(allowlist-autoadd.agi,)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:23] Macro("Local/916475106450@from-internal-0000001f;2", "dialout-trunk-predial-hook,") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?skipcrm") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:25] Set("Local/916475106450@from-internal-0000001f;2", "__CRM_DIRECTION=OUTBOUND") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:26] Set("Local/916475106450@from-internal-0000001f;2", "__CRM_DESTINATION=16475106450") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:27] Set("Local/916475106450@from-internal-0000001f;2", "__CRM_SOURCE=") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:28] AGI("Local/916475106450@from-internal-0000001f;2", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] res_agi.c: <Local/916475106450@from-internal-0000001f;2>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:29] Set("Local/916475106450@from-internal-0000001f;2", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:30] NoOp("Local/916475106450@from-internal-0000001f;2", "CRM Finished") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?bypass,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CONNECTEDLINE(num,i)=16475106450)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:33] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CONNECTEDLINE(name,i)=CID:6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:34] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:35] GotoIf("Local/916475106450@from-internal-0000001f;2", "0?customtrunk") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:36] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:37] Set("Local/916475106450@from-internal-0000001f;2", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-dialout-trunk:38] Dial("Local/916475106450@from-internal-0000001f;2", "PJSIP/16475106450@VoIP.ms,300,Tb(func-apply-sipheaders^s^1,(3))U(sub-send-obroute-email^16475106450^916475106450^3^1731111669^^6474784908)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] app_stack.c: PJSIP/VoIP.ms-0000002c Internal Gosub(func-apply-sipheaders,s,1(3)) start
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/VoIP.ms-0000002c", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/VoIP.ms-0000002c", "Applying SIP Headers to channel PJSIP/VoIP.ms-0000002c") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/VoIP.ms-0000002c", "localchan=VoIP.ms-0000002c") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/VoIP.ms-0000002c", "DialMCEXT=VoIP.ms") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/VoIP.ms-0000002c", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,VoIP.ms,1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/VoIP.ms-0000002c", "TECH=PJSIP") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/VoIP.ms-0000002c", "SIPHEADERKEYS=Alert-Info") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/VoIP.ms-0000002c", "1") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:9] Set("PJSIP/VoIP.ms-0000002c", "sipheader=unset") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/VoIP.ms-0000002c", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2024-11-09 00:21:09] ERROR[13750] res_pjsip_header_funcs.c: No headers had been previously added to this session.
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/VoIP.ms-0000002c", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/VoIP.ms-0000002c", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/VoIP.ms-0000002c", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:14] EndWhile("PJSIP/VoIP.ms-0000002c", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/VoIP.ms-0000002c", "0") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@func-apply-sipheaders:15] Return("PJSIP/VoIP.ms-0000002c", "") in new stack
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] app_stack.c: Spawn extension (func-apply-sipheaders, s, 15) exited non-zero on 'PJSIP/VoIP.ms-0000002c'
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] app_stack.c: PJSIP/VoIP.ms-0000002c Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
[2024-11-09 00:21:09] VERBOSE[10859][C-0000002d] app_dial.c: Called PJSIP/16475106450@VoIP.ms
[2024-11-09 00:21:11] VERBOSE[10839][C-0000002c] app_dial.c: PJSIP/VoIP.ms-0000002b is making progress passing it to Local/916475106450@from-internal-0000001d;2
[2024-11-09 00:21:11] VERBOSE[10838][C-0000002c] app_dial.c: Local/916475106450@from-internal-0000001d;1 is making progress passing it to Local/6475106450@webcallback-1-0000001c;2
[2024-11-09 00:21:11] VERBOSE[10859][C-0000002d] app_dial.c: PJSIP/VoIP.ms-0000002c is making progress passing it to Local/916475106450@from-internal-0000001f;2
[2024-11-09 00:21:11] VERBOSE[10858][C-0000002d] app_dial.c: Local/916475106450@from-internal-0000001f;1 is making progress passing it to Local/6475106450@webcallback-1-0000001e;2
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_dial.c: PJSIP/VoIP.ms-0000002b answered Local/916475106450@from-internal-0000001d;2
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_stack.c: PJSIP/VoIP.ms-0000002b Internal Gosub(sub-send-obroute-email,s,1(16475106450,916475106450,3,1731111669,,6474784908)) start
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf("PJSIP/VoIP.ms-0000002b", "0?sendEmail") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp("PJSIP/VoIP.ms-0000002b", "email notifications disabled..exiting.") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@sub-send-obroute-email:3] Return("PJSIP/VoIP.ms-0000002b", "") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_stack.c: Spawn extension (sub-send-obroute-email, s, 3) exited non-zero on 'PJSIP/VoIP.ms-0000002b'
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_stack.c: PJSIP/VoIP.ms-0000002b Internal Gosub(sub-send-obroute-email,s,1(16475106450,916475106450,3,1731111669,,6474784908)) complete GOSUB_RETVAL=
[2024-11-09 00:21:12] VERBOSE[10838][C-0000002c] app_dial.c: Local/916475106450@from-internal-0000001d;1 answered Local/6475106450@webcallback-1-0000001c;2
[2024-11-09 00:21:12] VERBOSE[10837] dial.c: Local/6475106450@webcallback-1-0000001c;1 answered
[2024-11-09 00:21:12] VERBOSE[10837][C-0000002e] pbx.c: Executing [1@ext-miscdests:1] NoOp("Local/6475106450@webcallback-1-0000001c;1", "MiscDest: Blue Tile Installer") in new stack
[2024-11-09 00:21:12] VERBOSE[10837][C-0000002e] pbx.c: Executing [1@ext-miscdests:2] Goto("Local/6475106450@webcallback-1-0000001c;1", "from-internal,6478684721,1") in new stack
[2024-11-09 00:21:12] VERBOSE[10837][C-0000002e] pbx_builtins.c: Goto (from-internal,6478684721,1)
[2024-11-09 00:21:12] VERBOSE[10837][C-0000002e] pbx.c: Executing [6478684721@from-internal:1] ResetCDR("Local/6475106450@webcallback-1-0000001c;1", "") in new stack
[2024-11-09 00:21:12] VERBOSE[10877][C-0000002c] bridge_channel.c: Channel PJSIP/VoIP.ms-0000002b joined 'simple_bridge' basic-bridge <8d35f1bb-1aef-44be-82af-f52fad2eef75>
[2024-11-09 00:21:12] VERBOSE[10837][C-0000002e] pbx.c: Executing [6478684721@from-internal:2] NoCDR("Local/6475106450@webcallback-1-0000001c;1", "") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] bridge_channel.c: Channel Local/916475106450@from-internal-0000001d;2 joined 'simple_bridge' basic-bridge <8d35f1bb-1aef-44be-82af-f52fad2eef75>
[2024-11-09 00:21:12] VERBOSE[10878][C-0000002c] bridge_channel.c: Channel Local/916475106450@from-internal-0000001d;1 joined 'simple_bridge' basic-bridge <3cc37fa6-61cc-4320-bedf-dc127dc6e13a>
[2024-11-09 00:21:12] VERBOSE[10838][C-0000002c] bridge_channel.c: Channel Local/6475106450@webcallback-1-0000001c;2 joined 'simple_bridge' basic-bridge <3cc37fa6-61cc-4320-bedf-dc127dc6e13a>
[2024-11-09 00:21:12] VERBOSE[10837][C-0000002e] pbx.c: Executing [6478684721@from-internal:3] Progress("Local/6475106450@webcallback-1-0000001c;1", "") in new stack
[2024-11-09 00:21:12] VERBOSE[10837][C-0000002e] pbx.c: Executing [6478684721@from-internal:4] Wait("Local/6475106450@webcallback-1-0000001c;1", "1") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] bridge_channel.c: Channel PJSIP/VoIP.ms-0000002b left 'simple_bridge' basic-bridge <8d35f1bb-1aef-44be-82af-f52fad2eef75>
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] bridge_channel.c: Channel Local/916475106450@from-internal-0000001d;1 left 'simple_bridge' basic-bridge <3cc37fa6-61cc-4320-bedf-dc127dc6e13a>
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] bridge_channel.c: Channel PJSIP/VoIP.ms-0000002b swapped with Local/916475106450@from-internal-0000001d;1 into 'simple_bridge' basic-bridge <3cc37fa6-61cc-4320-bedf-dc127dc6e13a>
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] bridge_channel.c: Channel Local/916475106450@from-internal-0000001d;2 left 'simple_bridge' basic-bridge <8d35f1bb-1aef-44be-82af-f52fad2eef75>
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_macro.c: Spawn extension (macro-dialout-trunk, s, 38) exited non-zero on 'Local/916475106450@from-internal-0000001d;2' in macro 'dialout-trunk'
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Spawn extension (from-internal, 916475106450, 12) exited non-zero on 'Local/916475106450@from-internal-0000001d;2'
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [h@from-internal:1] Macro("Local/916475106450@from-internal-0000001d;2", "hangupcall") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-hangupcall:1] Set("Local/916475106450@from-internal-0000001d;2", "__MCVMSTATUS=") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-hangupcall:2] Gosub("Local/916475106450@from-internal-0000001d;2", "app-missedcall-hangup,s,1()") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("Local/916475106450@from-internal-0000001d;2", "Dialed: s") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("Local/916475106450@from-internal-0000001d;2", "Caller: ") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?exit") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (app-missedcall-hangup,s,8)
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@app-missedcall-hangup:8] Return("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-hangupcall:3] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?theend") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (macro-hangupcall,s,5)
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-hangupcall:5] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(CDR(recordingfile)=)") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/916475106450@from-internal-0000001d;2' in macro 'hangupcall'
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/916475106450@from-internal-0000001d;2'
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_stack.c: Local/916475106450@from-internal-0000001d;2 Internal Gosub(crm-hangup,s,1) start
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/916475106450@from-internal-0000001d;2", "Sending Hangup to CRM") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/916475106450@from-internal-0000001d;2", "HANGUP CAUSE: 16") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/916475106450@from-internal-0000001d;2", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/916475106450@from-internal-0000001d;2", "MASTER CHANNEL: 1731111669.102 = 1731111669.99") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/916475106450@from-internal-0000001d;2", "1?return") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx_builtins.c: Goto (crm-hangup,s,8)
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] pbx.c: Executing [s@crm-hangup:8] Return("Local/916475106450@from-internal-0000001d;2", "") in new stack
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/916475106450@from-internal-0000001d;2'
[2024-11-09 00:21:12] VERBOSE[10839][C-0000002c] app_stack.c: Local/916475106450@from-internal-0000001d;2 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2024-11-09 00:21:13] VERBOSE[10837][C-0000002e] pbx.c: Executing [6478684721@from-internal:5] Playback("Local/6475106450@webcallback-1-0000001c;1", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2024-11-09 00:21:13] VERBOSE[10837][C-0000002e] file.c: <Local/6475106450@webcallback-1-0000001c;1> Playing 'silence/1.slin16' (language 'en')
[2024-11-09 00:21:14] VERBOSE[10837][C-0000002e] file.c: <Local/6475106450@webcallback-1-0000001c;1> Playing 'cannot-complete-as-dialed.slin16' (language 'en')
[2024-11-09 00:21:17] VERBOSE[10837][C-0000002e] file.c: <Local/6475106450@webcallback-1-0000001c;1> Playing 'check-number-dial-again.slin16' (language 'en')
[2024-11-09 00:21:19] VERBOSE[10837][C-0000002e] pbx.c: Executing [6478684721@from-internal:6] Wait("Local/6475106450@webcallback-1-0000001c;1", "1") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_dial.c: PJSIP/VoIP.ms-0000002c answered Local/916475106450@from-internal-0000001f;2
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_stack.c: PJSIP/VoIP.ms-0000002c Internal Gosub(sub-send-obroute-email,s,1(16475106450,916475106450,3,1731111669,,6474784908)) start
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf("PJSIP/VoIP.ms-0000002c", "0?sendEmail") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp("PJSIP/VoIP.ms-0000002c", "email notifications disabled..exiting.") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@sub-send-obroute-email:3] Return("PJSIP/VoIP.ms-0000002c", "") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_stack.c: Spawn extension (sub-send-obroute-email, s, 3) exited non-zero on 'PJSIP/VoIP.ms-0000002c'
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_stack.c: PJSIP/VoIP.ms-0000002c Internal Gosub(sub-send-obroute-email,s,1(16475106450,916475106450,3,1731111669,,6474784908)) complete GOSUB_RETVAL=
[2024-11-09 00:21:19] VERBOSE[10858][C-0000002d] app_dial.c: Local/916475106450@from-internal-0000001f;1 answered Local/6475106450@webcallback-1-0000001e;2
[2024-11-09 00:21:19] VERBOSE[10857] dial.c: Local/6475106450@webcallback-1-0000001e;1 answered
[2024-11-09 00:21:19] VERBOSE[10857][C-0000002f] pbx.c: Executing [1@ext-miscdests:1] NoOp("Local/6475106450@webcallback-1-0000001e;1", "MiscDest: Blue Tile Installer") in new stack
[2024-11-09 00:21:19] VERBOSE[10857][C-0000002f] pbx.c: Executing [1@ext-miscdests:2] Goto("Local/6475106450@webcallback-1-0000001e;1", "from-internal,6478684721,1") in new stack
[2024-11-09 00:21:19] VERBOSE[10857][C-0000002f] pbx_builtins.c: Goto (from-internal,6478684721,1)
[2024-11-09 00:21:19] VERBOSE[10857][C-0000002f] pbx.c: Executing [6478684721@from-internal:1] ResetCDR("Local/6475106450@webcallback-1-0000001e;1", "") in new stack
[2024-11-09 00:21:19] VERBOSE[10893][C-0000002d] bridge_channel.c: Channel PJSIP/VoIP.ms-0000002c joined 'simple_bridge' basic-bridge <4cf935b7-9e63-4e3a-9688-fb11b12d6c30>
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] bridge_channel.c: Channel Local/916475106450@from-internal-0000001f;2 joined 'simple_bridge' basic-bridge <4cf935b7-9e63-4e3a-9688-fb11b12d6c30>
[2024-11-09 00:21:19] VERBOSE[10857][C-0000002f] pbx.c: Executing [6478684721@from-internal:2] NoCDR("Local/6475106450@webcallback-1-0000001e;1", "") in new stack
[2024-11-09 00:21:19] VERBOSE[10857][C-0000002f] pbx.c: Executing [6478684721@from-internal:3] Progress("Local/6475106450@webcallback-1-0000001e;1", "") in new stack
[2024-11-09 00:21:19] VERBOSE[10857][C-0000002f] pbx.c: Executing [6478684721@from-internal:4] Wait("Local/6475106450@webcallback-1-0000001e;1", "1") in new stack
[2024-11-09 00:21:19] VERBOSE[10894][C-0000002d] bridge_channel.c: Channel Local/916475106450@from-internal-0000001f;1 joined 'simple_bridge' basic-bridge <4ca0da0c-7430-471a-91d5-2641d1bd102b>
[2024-11-09 00:21:19] VERBOSE[10858][C-0000002d] bridge_channel.c: Channel Local/6475106450@webcallback-1-0000001e;2 joined 'simple_bridge' basic-bridge <4ca0da0c-7430-471a-91d5-2641d1bd102b>
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] bridge_channel.c: Channel PJSIP/VoIP.ms-0000002c left 'simple_bridge' basic-bridge <4cf935b7-9e63-4e3a-9688-fb11b12d6c30>
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] bridge_channel.c: Channel Local/916475106450@from-internal-0000001f;1 left 'simple_bridge' basic-bridge <4ca0da0c-7430-471a-91d5-2641d1bd102b>
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] bridge_channel.c: Channel PJSIP/VoIP.ms-0000002c swapped with Local/916475106450@from-internal-0000001f;1 into 'simple_bridge' basic-bridge <4ca0da0c-7430-471a-91d5-2641d1bd102b>
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] bridge_channel.c: Channel Local/916475106450@from-internal-0000001f;2 left 'simple_bridge' basic-bridge <4cf935b7-9e63-4e3a-9688-fb11b12d6c30>
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_macro.c: Spawn extension (macro-dialout-trunk, s, 38) exited non-zero on 'Local/916475106450@from-internal-0000001f;2' in macro 'dialout-trunk'
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Spawn extension (from-internal, 916475106450, 12) exited non-zero on 'Local/916475106450@from-internal-0000001f;2'
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [h@from-internal:1] Macro("Local/916475106450@from-internal-0000001f;2", "hangupcall") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-hangupcall:1] Set("Local/916475106450@from-internal-0000001f;2", "__MCVMSTATUS=") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-hangupcall:2] Gosub("Local/916475106450@from-internal-0000001f;2", "app-missedcall-hangup,s,1()") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("Local/916475106450@from-internal-0000001f;2", "Dialed: s") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("Local/916475106450@from-internal-0000001f;2", "Caller: ") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?exit") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (app-missedcall-hangup,s,8)
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@app-missedcall-hangup:8] Return("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-hangupcall:3] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?theend") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (macro-hangupcall,s,5)
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-hangupcall:5] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(CDR(recordingfile)=)") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/916475106450@from-internal-0000001f;2' in macro 'hangupcall'
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/916475106450@from-internal-0000001f;2'
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_stack.c: Local/916475106450@from-internal-0000001f;2 Internal Gosub(crm-hangup,s,1) start
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/916475106450@from-internal-0000001f;2", "Sending Hangup to CRM") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/916475106450@from-internal-0000001f;2", "HANGUP CAUSE: 16") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/916475106450@from-internal-0000001f;2", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/916475106450@from-internal-0000001f;2", "MASTER CHANNEL: 1731111669.107 = 1731111669.104") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/916475106450@from-internal-0000001f;2", "1?return") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx_builtins.c: Goto (crm-hangup,s,8)
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] pbx.c: Executing [s@crm-hangup:8] Return("Local/916475106450@from-internal-0000001f;2", "") in new stack
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/916475106450@from-internal-0000001f;2'
[2024-11-09 00:21:19] VERBOSE[10859][C-0000002d] app_stack.c: Local/916475106450@from-internal-0000001f;2 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2024-11-09 00:21:20] VERBOSE[10837][C-0000002e] pbx.c: Executing [6478684721@from-internal:7] Congestion("Local/6475106450@webcallback-1-0000001c;1", "20") in new stack
[2024-11-09 00:21:20] VERBOSE[10857][C-0000002f] pbx.c: Executing [6478684721@from-internal:5] Playback("Local/6475106450@webcallback-1-0000001e;1", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2024-11-09 00:21:20] VERBOSE[10857][C-0000002f] file.c: <Local/6475106450@webcallback-1-0000001e;1> Playing 'silence/1.slin16' (language 'en')
[2024-11-09 00:21:21] VERBOSE[10857][C-0000002f] file.c: <Local/6475106450@webcallback-1-0000001e;1> Playing 'cannot-complete-as-dialed.slin16' (language 'en')
[2024-11-09 00:21:24] VERBOSE[10857][C-0000002f] file.c: <Local/6475106450@webcallback-1-0000001e;1> Playing 'check-number-dial-again.slin16' (language 'en')
[2024-11-09 00:21:26] VERBOSE[10857][C-0000002f] pbx.c: Executing [6478684721@from-internal:6] Wait("Local/6475106450@webcallback-1-0000001e;1", "1") in new stack
[2024-11-09 00:21:27] VERBOSE[10857][C-0000002f] pbx.c: Executing [6478684721@from-internal:7] Congestion("Local/6475106450@webcallback-1-0000001e;1", "20") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] bridge_channel.c: Channel PJSIP/VoIP.ms-0000002c left 'simple_bridge' basic-bridge <4ca0da0c-7430-471a-91d5-2641d1bd102b>
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] app_stack.c: PJSIP/VoIP.ms-0000002c Internal Gosub(app-missedcall-hangup,VoIP.ms,1) start
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:1] NoOp("PJSIP/VoIP.ms-0000002c", "Dialed: VoIP.ms") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:2] NoOp("PJSIP/VoIP.ms-0000002c", "Caller: ") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:3] GotoIf("PJSIP/VoIP.ms-0000002c", "0?exit") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:4] Set("PJSIP/VoIP.ms-0000002c", "EXTENNUM=VoIP.ms") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:5] Set("PJSIP/VoIP.ms-0000002c", "FEXTENNUM=VoIP.ms") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:6] GotoIf("PJSIP/VoIP.ms-0000002c", "0?exit") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:7] AGI("PJSIP/VoIP.ms-0000002c", "agi://127.0.0.1/missedcallnotify.php,VoIP.ms,,VoIP.ms,0,,PJSIP/VoIP.ms-0000002c,,,,") in new stack
[2024-11-09 00:21:28] VERBOSE[10858][C-0000002d] bridge_channel.c: Channel Local/6475106450@webcallback-1-0000001e;2 left 'simple_bridge' basic-bridge <4ca0da0c-7430-471a-91d5-2641d1bd102b>
[2024-11-09 00:21:28] VERBOSE[10858][C-0000002d] pbx.c: Spawn extension (webcallback-1, 6475106450, 2) exited non-zero on 'Local/6475106450@webcallback-1-0000001e;2'
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Spawn extension (from-internal, 6478684721, 7) exited non-zero on 'Local/6475106450@webcallback-1-0000001e;1'
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [h@from-internal:1] Macro("Local/6475106450@webcallback-1-0000001e;1", "hangupcall") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@macro-hangupcall:1] Set("Local/6475106450@webcallback-1-0000001e;1", "__MCVMSTATUS=") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@macro-hangupcall:2] Gosub("Local/6475106450@webcallback-1-0000001e;1", "app-missedcall-hangup,s,1()") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("Local/6475106450@webcallback-1-0000001e;1", "Dialed: s") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("Local/6475106450@webcallback-1-0000001e;1", "Caller: ") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("Local/6475106450@webcallback-1-0000001e;1", "0?exit") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:4] Set("Local/6475106450@webcallback-1-0000001e;1", "EXTENNUM=s") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:5] Set("Local/6475106450@webcallback-1-0000001e;1", "FEXTENNUM=s") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:6] GotoIf("Local/6475106450@webcallback-1-0000001e;1", "0?exit") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:7] AGI("Local/6475106450@webcallback-1-0000001e;1", "agi://127.0.0.1/missedcallnotify.php,s,,s,0,,Local/6475106450@webcallback-1-0000001e;1,,,,") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] res_agi.c: <PJSIP/VoIP.ms-0000002c>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] pbx.c: Executing [VoIP.ms@app-missedcall-hangup:8] Return("PJSIP/VoIP.ms-0000002c", "") in new stack
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] app_stack.c: Spawn extension (app-missedcall-hangup, VoIP.ms, 8) exited non-zero on 'PJSIP/VoIP.ms-0000002c'
[2024-11-09 00:21:28] VERBOSE[10893][C-0000002d] app_stack.c: PJSIP/VoIP.ms-0000002c Internal Gosub(app-missedcall-hangup,VoIP.ms,1) complete GOSUB_RETVAL=
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] res_agi.c: <Local/6475106450@webcallback-1-0000001e;1>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@app-missedcall-hangup:8] Return("Local/6475106450@webcallback-1-0000001e;1", "") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@macro-hangupcall:3] GotoIf("Local/6475106450@webcallback-1-0000001e;1", "1?theend") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx_builtins.c: Goto (macro-hangupcall,s,5)
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@macro-hangupcall:5] ExecIf("Local/6475106450@webcallback-1-0000001e;1", "0?Set(CDR(recordingfile)=)") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/6475106450@webcallback-1-0000001e;1", "") in new stack
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/6475106450@webcallback-1-0000001e;1' in macro 'hangupcall'
[2024-11-09 00:21:28] VERBOSE[10857][C-0000002f] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/6475106450@webcallback-1-0000001e;1'