[2024-11-29 14:38:57] VERBOSE[1912][C-000040ac] netsock2.c: Using SIP RTP TOS bits 184
[2024-11-29 14:38:57] VERBOSE[1912][C-000040ac] netsock2.c: Using SIP RTP CoS mark 5
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:1] Set("SIP/MTS-000094a8", "__DIRECTION=INBOUND") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:2] Gosub("SIP/MTS-000094a8", "sub-record-check,s,1(in,3466635911,no)") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/MTS-000094a8", "0?initialized") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:2] Set("SIP/MTS-000094a8", "__REC_STATUS=INITIALIZED") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:3] Set("SIP/MTS-000094a8", "NOW=1732873137") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:4] Set("SIP/MTS-000094a8", "__DAY=29") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:5] Set("SIP/MTS-000094a8", "__MONTH=11") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:6] Set("SIP/MTS-000094a8", "__YEAR=2024") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:7] Set("SIP/MTS-000094a8", "__TIMESTR=20241129-143857") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:8] Set("SIP/MTS-000094a8", "__FROMEXTEN=unknown") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:9] Set("SIP/MTS-000094a8", "__MON_FMT=wav") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/MTS-000094a8", "Recordings initialized") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/MTS-000094a8", "0?Set(ARG3=dontcare)") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:12] Set("SIP/MTS-000094a8", "REC_POLICY_MODE_SAVE=") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/MTS-000094a8", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/MTS-000094a8", "2?checkaction") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/MTS-000094a8", "1?sub-record-check,in,1") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,in,1)
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Inbound Recording Check to 3466635911") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [in@sub-record-check:2] Set("SIP/MTS-000094a8", "FROMEXTEN=unknown") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/MTS-000094a8", "11?Set(FROMEXTEN=83463259011)") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/MTS-000094a8", "recordcheck,1(no,in,3466635911)") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Starting recording check against no") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/MTS-000094a8", "no") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,recordcheck,12)
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:12] Set("SIP/MTS-000094a8", "__REC_POLICY_MODE=NO") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:13] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [in@sub-record-check:5] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:3] Set("SIP/MTS-000094a8", "CHANNEL(tonezone)=us") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:4] Set("SIP/MTS-000094a8", "__FROM_DID=3466635911") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:5] Set("SIP/MTS-000094a8", "returnhere=1") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:6] Gosub("SIP/MTS-000094a8", "app-blacklist-check,s,1()") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/MTS-000094a8", "0?blacklisted") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/MTS-000094a8", "CALLED_BLACKLIST=1") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:7] Set("SIP/MTS-000094a8", "CDR(did)=3466635911") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:8] GotoIf("SIP/MTS-000094a8", "0?") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:9] ExecIf("SIP/MTS-000094a8", "0 ?Set(CALLERID(name)=83463259011)") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:10] Set("SIP/MTS-000094a8", "__MOHCLASS=") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:11] Set("SIP/MTS-000094a8", "__REVERSAL_REJECT=TRUE") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:12] GotoIf("SIP/MTS-000094a8", "0?post-reverse-charge") in new stack
[2024-11-29 14:38:57] WARNING[201530][C-000040ac] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:13] GotoIf("SIP/MTS-000094a8", "0?macro-hangupcall") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:14] NoOp("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:15] Set("SIP/MTS-000094a8", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:16] Set("SIP/MTS-000094a8", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:17] Set("SIP/MTS-000094a8", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:18] Set("SIP/MTS-000094a8", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:19] NoOp("SIP/MTS-000094a8", "CallerID Entry Point") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [3466635911@from-trunk:20] Goto("SIP/MTS-000094a8", "ivr-1,s,1") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ivr-1,s,1)
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:1] Set("SIP/MTS-000094a8", "INVALID_LOOPCOUNT=0") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:2] Set("SIP/MTS-000094a8", "_IVR_CONTEXT_ivr-1=") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:3] Set("SIP/MTS-000094a8", "_IVR_CONTEXT=ivr-1") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:4] Set("SIP/MTS-000094a8", "__IVR_RETVM=") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:5] GotoIf("SIP/MTS-000094a8", "0?skip") in new stack
[2024-11-29 14:38:57] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:6] Answer("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:38:58] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:7] Set("SIP/MTS-000094a8", "IVR_MSG=custom/Hello_ASU") in new stack
[2024-11-29 14:38:58] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:8] Set("SIP/MTS-000094a8", "TIMEOUT(digit)=3") in new stack
[2024-11-29 14:38:58] VERBOSE[201530][C-000040ac] func_timeout.c: Digit timeout set to 3.000
[2024-11-29 14:38:58] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:9] ExecIf("SIP/MTS-000094a8", "1?Background(custom/Hello_ASU)") in new stack
[2024-11-29 14:38:58] VERBOSE[201530][C-000040ac] file.c: <SIP/MTS-000094a8> Playing 'custom/Hello_ASU.alaw' (language 'ru')
[2024-11-29 14:38:59] DTMF[201530][C-000040ac] channel.c: DTMF begin '1' received on SIP/MTS-000094a8
[2024-11-29 14:38:59] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '1' on SIP/MTS-000094a8
[2024-11-29 14:38:59] DTMF[201530][C-000040ac] channel.c: DTMF end '1' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:38:59] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '1' on SIP/MTS-000094a8
[2024-11-29 14:38:59] DTMF[201530][C-000040ac] channel.c: DTMF begin '0' received on SIP/MTS-000094a8
[2024-11-29 14:38:59] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '0' on SIP/MTS-000094a8
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF end '0' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '0' on SIP/MTS-000094a8
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF begin '9' received on SIP/MTS-000094a8
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '9' on SIP/MTS-000094a8
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF end '9' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '9' on SIP/MTS-000094a8
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF begin '7' received on SIP/MTS-000094a8
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '7' on SIP/MTS-000094a8
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF end '7' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:00] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '7' on SIP/MTS-000094a8
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ivr-1:1] Set("SIP/MTS-000094a8", "__ivrreturn=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ivr-1:2] Goto("SIP/MTS-000094a8", "from-did-direct,1097,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (from-did-direct,1097,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@from-did-direct:1] GotoIf("SIP/MTS-000094a8", "0?ext-local,1097,1:followme-check,1097,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-check,1097,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-check:1] Gosub("SIP/MTS-000094a8", "followme-sub,1097,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:1] GotoIf("SIP/MTS-000094a8", "0?skipclid") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:2] Macro("SIP/MTS-000094a8", "user-callerid,") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/MTS-000094a8", "TOUCH_MONITOR=1732873137.38926") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/MTS-000094a8", "CHANCONTEXT=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/MTS-000094a8", "CHANCONTEXT=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/MTS-000094a8", "CHANEXTENCONTEXT=MTS-000094a8") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/MTS-000094a8", "CHANEXTEN=MTS-000094a8") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:6] Set("SIP/MTS-000094a8", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/MTS-000094a8", "AMPUSER=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:8] Set("SIP/MTS-000094a8", "HOTDESCKCHAN=MTS-000094a8") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/MTS-000094a8", "HOTDESKEXTEN=MTS") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/MTS-000094a8", "HOTDESKCALL=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SIP/MTS-000094a8", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SIP/MTS-000094a8", "0?Set(CALLERID(name)=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/MTS-000094a8", "0?report") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/MTS-000094a8", "1?Set(REALCALLERIDNUM=83463259011)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/MTS-000094a8", "AMPUSER=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/MTS-000094a8", "0?limit") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/MTS-000094a8", "AMPUSERCIDNAME=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/MTS-000094a8", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/MTS-000094a8", "1?report") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/MTS-000094a8", "Macro Depth is 1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/MTS-000094a8", "1?report2:macroerror") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/MTS-000094a8", "0?continue") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/MTS-000094a8", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/MTS-000094a8", "__TTL=64") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/MTS-000094a8", "1?continue") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/MTS-000094a8", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/MTS-000094a8", "CALLERID(name)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/MTS-000094a8", "0?cnum") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/MTS-000094a8", "CDR(cnam)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/MTS-000094a8", "CDR(cnum)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/MTS-000094a8", "CHANNEL(language)=ru") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:3] Set("SIP/MTS-000094a8", "DIAL_OPTIONS=HhTtrI") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:4] Set("SIP/MTS-000094a8", "CONNECTEDLINE(num,i)=1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:5] Gosub("SIP/MTS-000094a8", "sub-presencestate-display,s,1(1097)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-presencestate-display:1] Goto("SIP/MTS-000094a8", "state-available,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-presencestate-display,state-available,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [state-available@sub-presencestate-display:1] Set("SIP/MTS-000094a8", "PRESENCESTATE_DISPLAY=(Available)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [state-available@sub-presencestate-display:2] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:6] Set("SIP/MTS-000094a8", "CONNECTEDLINE(name)=Острожной Д.А.(Available)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:7] Set("SIP/MTS-000094a8", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:8] Set("SIP/MTS-000094a8", "__EXTTOCALL=1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:9] Set("SIP/MTS-000094a8", "__PICKUPMARK=1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:10] Macro("SIP/MTS-000094a8", "blkvm-setifempty,") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/MTS-000094a8", "1?init") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("SIP/MTS-000094a8", "__BLKVM_CHANNEL=SIP/MTS-000094a8") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("SIP/MTS-000094a8", "SHARED(BLKVM,SIP/MTS-000094a8)=TRUE") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("SIP/MTS-000094a8", "GOSUB_RETVAL=TRUE") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:11] GotoIf("SIP/MTS-000094a8", "1?skipov") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,14)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:14] Set("SIP/MTS-000094a8", "RRNODEST=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:15] Set("SIP/MTS-000094a8", "__NODEST=1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:16] GosubIf("SIP/MTS-000094a8", "1?sub-fmsetcid,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-fmsetcid:1] Goto("SIP/MTS-000094a8", "s-fixed,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-fmsetcid,s-fixed,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s-fixed@sub-fmsetcid:1] ExecIf("SIP/MTS-000094a8", "1?Set(__TRUNKCIDOVERRIDE=3466635911)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s-fixed@sub-fmsetcid:2] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:17] GotoIf("SIP/MTS-000094a8", "1?skipprepend") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,19)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:19] Set("SIP/MTS-000094a8", "RecordMethod=Group") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:20] Gosub("SIP/MTS-000094a8", "sub-record-check,s,1(exten,1097,)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/MTS-000094a8", "11?initialized") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,s,10)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/MTS-000094a8", "Recordings initialized") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/MTS-000094a8", "1?Set(ARG3=dontcare)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:12] Set("SIP/MTS-000094a8", "REC_POLICY_MODE_SAVE=NO") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/MTS-000094a8", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/MTS-000094a8", "5?checkaction") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/MTS-000094a8", "1?sub-record-check,exten,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Exten Recording Check between 83463259011 and 1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/MTS-000094a8", "CALLTYPE=external") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/MTS-000094a8", "0?Set(CALLTYPE=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/MTS-000094a8", "CALLEE=dontcare") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/MTS-000094a8", "0?Set(CALLEE=dontcare)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/MTS-000094a8", "1?callee") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/MTS-000094a8", "recordcheck,1(dontcare,external,1097)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Starting recording check against dontcare") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/MTS-000094a8", "dontcare") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:21] GotoIf("SIP/MTS-000094a8", "1?skipdring") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,24)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:24] Set("SIP/MTS-000094a8", "STRATEGY=ringall") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:25] Set("SIP/MTS-000094a8", "__RVOL=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:26] GotoIf("SIP/MTS-000094a8", "0?skipsimple") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:27] GotoIf("SIP/MTS-000094a8", "1 ?skipsimple") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,29)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:29] Set("SIP/MTS-000094a8", "RingGroupMethod=ringall") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:30] Set("SIP/MTS-000094a8", "_FMGRP=1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:31] GotoIf("SIP/MTS-000094a8", "1?DIALGRP") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,35)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:35] ExecIf("SIP/MTS-000094a8", "1?Set(DOPTS=HhTtrI):Set(DOPTS=m(Ring)HhTtI)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:36] Set("SIP/MTS-000094a8", "__ALT_CONFIRM_MSG=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:37] GotoIf("SIP/MTS-000094a8", "0?doconfirm") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:38] GotoIf("SIP/MTS-000094a8", "0?ringallv21") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:39] Macro("SIP/MTS-000094a8", "dial,60,HhTtrI,89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:1] NoOp("SIP/MTS-000094a8", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:2] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:3] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:4] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:5] ExecIf("SIP/MTS-000094a8", "0?Set(CHANNEL(musicclass)=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:6] Gosub("SIP/MTS-000094a8", "dialparties,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:1] NoOp("SIP/MTS-000094a8", "Starting New Dialparties") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:2] ClearHash("SIP/MTS-000094a8", "dialparties_FMFMLIST") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:3] ClearHash("SIP/MTS-000094a8", "dialparties_EXTLIST") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:4] ClearHash("SIP/MTS-000094a8", "dialparties") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:5] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:7] NoOp("SIP/MTS-000094a8", "ARG1: 60") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:8] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=2") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:9] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:7] NoOp("SIP/MTS-000094a8", "ARG2: HhTtrI") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:8] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=3") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:9] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:7] NoOp("SIP/MTS-000094a8", "ARG3: 89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:8] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=4") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:9] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:10] Set("SIP/MTS-000094a8", "HASH(dialparties,CWINUSEBUSY)=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:11] Set("SIP/MTS-000094a8", "HASH(dialparties,CWIGNORE)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:12] Set("SIP/MTS-000094a8", "HASH(dialparties,CFIGNORE)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:13] Set("SIP/MTS-000094a8", "HASH(dialparties,SIGNORE)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:14] Set("SIP/MTS-000094a8", "HASH(dialparties,AMPUSER)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:15] Set("SIP/MTS-000094a8", "HASH(dialparties,FMGRP)=1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:16] Set("SIP/MTS-000094a8", "HASH(dialparties,NODEST)=1097") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:17] Set("SIP/MTS-000094a8", "HASH(dialparties,CIDNUM)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:18] Set("SIP/MTS-000094a8", "HASH(dialparties,CIDNAME)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:19] NoOp("SIP/MTS-000094a8", "Caller ID name is 83463259011 number is 83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:20] NoOp("SIP/MTS-000094a8", "CW Ignore is: ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:21] NoOp("SIP/MTS-000094a8", "CF Ignore is: ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:22] NoOp("SIP/MTS-000094a8", "CW IN_USE/BUSY is: 1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:23] Set("SIP/MTS-000094a8", "HASH(dialparties,QUEUEWAIT)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:24] GosubIf("SIP/MTS-000094a8", "0?dialparties-queuewait,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:25] Set("SIP/MTS-000094a8", "__KEEPCID=TRUE") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:26] Set("SIP/MTS-000094a8", "DIALSTATUS_CW=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:27] Set("SIP/MTS-000094a8", "HASH(dialparties,TIMER)=60") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:28] Set("SIP/MTS-000094a8", "HASH(dialparties,DIALOPTS)=HhTtrI") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:29] Set("SIP/MTS-000094a8", "HASH(dialparties,RGMETHOD)=ringall") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:30] Set("SIP/MTS-000094a8", "HASH(dialparties,ALERTINFO)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:31] GosubIf("SIP/MTS-000094a8", "0?dialparties-alertinfo,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:32] GosubIf("SIP/MTS-000094a8", "0?dialparties-setheader,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:33] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,PR_DIALSTATUS)=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:34] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,USE_CONFIRMATION)=FALSE)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:35] Set("SIP/MTS-000094a8", "HASH(dialparties,RINGGROUP_INDEX)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:36] NoOp("SIP/MTS-000094a8", "USE_CONFIRMATION: FALSE") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:37] NoOp("SIP/MTS-000094a8", "RINGGROUP_INDEX: ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:38] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,TIMER)=0)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:39] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,DIALOPTS)=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:40] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,RGMETHOD)=none)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:41] NoOp("SIP/MTS-000094a8", "Methodology of ring is ringall") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:42] Set("SIP/MTS-000094a8", "HASH(dialparties,RECALL_MASTERMODE)=ringall") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:43] Gosub("SIP/MTS-000094a8", "dialparties-setmodes,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setmodes:1] GotoIf("SIP/MTS-000094a8", "0?ringall,1:default,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-setmodes,default,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [default@dialparties-setmodes:1] Set("SIP/MTS-000094a8", "HASH(dialparties,PR_DIALSTATUS)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [default@dialparties-setmodes:2] Set("SIP/MTS-000094a8", "HASH(dialparties,MASTERMODE)=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [default@dialparties-setmodes:3] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:44] ExecIf("SIP/MTS-000094a8", "0?Set(__CWIGNORE=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:45] GosubIf("SIP/MTS-000094a8", "0?dialparties-invalidringmethod,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:46] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:47] Set("SIP/MTS-000094a8", "ARG_CNT=3") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:48] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:49] Set("SIP/MTS-000094a8", "TMPARG=89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:50] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:51] Set("SIP/MTS-000094a8", "HASH(dialparties_EXTLIST,0)=89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:52] NoOp("SIP/MTS-000094a8", "Added extension 89226556699# to extension map") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:53] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:54] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:50] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:55] Set("SIP/MTS-000094a8", "ARG_CNT=4") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:56] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:48] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:57] GosubIf("SIP/MTS-000094a8", "0?dialparties-preringprep,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:58] Set("SIP/MTS-000094a8", "HASH(dialparties,ALREADY_SCREENED)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:59] Set("SIP/MTS-000094a8", "HASH(dialparties,FROM_OUTSIDE)=3466635911") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:60] Set("SIP/MTS-000094a8", "HASH(dialparties,PRIMARY_EXT)=89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:61] GosubIf("SIP/MTS-000094a8", "0?dialparties-callscreencheck,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:62] Gosub("SIP/MTS-000094a8", "dialparties-checkcfextensions,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:1] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:2] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:3] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:4] NoOp("SIP/MTS-000094a8", "Working with extension 89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:5] GotoIf("SIP/MTS-000094a8", "1?skipcf") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkcfextensions,s,32)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:32] NoOp("SIP/MTS-000094a8", "Extension 89226556699# appears to be CF, skipping checks") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:33] Goto("SIP/MTS-000094a8", "next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkcfextensions,s,35)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:35] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:36] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:2] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:37] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:63] Gosub("SIP/MTS-000094a8", "dialparties-checkdndextensions,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:1] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:2] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:3] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:4] NoOp("SIP/MTS-000094a8", "Working with extension 89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:5] GotoIf("SIP/MTS-000094a8", "1?skipdnd") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkdndextensions,s,18)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:18] NoOp("SIP/MTS-000094a8", "Extension 89226556699# appears to be CF, skipping checks") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:19] Goto("SIP/MTS-000094a8", "next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkdndextensions,s,21)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:21] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:22] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:2] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:23] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:64] Gosub("SIP/MTS-000094a8", "dialparties-mainloop,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:1] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:2] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTLISTCOUNT)=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:3] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:4] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:5] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTLISTCOUNT)=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:6] NoOp("SIP/MTS-000094a8", "Working with extension 89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:7] Gosub("SIP/MTS-000094a8", "dialparties-isextavail,s,1(89226556699#)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-isextavail:1] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTSTATESTATUS)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-isextavail:2] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTSTATE_RESULT)=INUSE_ORIGINATOR):Set(HASH(dialparties,EXTSTATE_RESULT)=UNKNOWN)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-isextavail:3] GotoIf("SIP/MTS-000094a8", "1?UNKNOWN,1:default,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-isextavail,UNKNOWN,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [UNKNOWN@dialparties-isextavail:1] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTSTATESTATUS)=4") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [UNKNOWN@dialparties-isextavail:2] Goto("SIP/MTS-000094a8", "finish,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-isextavail,finish,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [finish@dialparties-isextavail:1] NoOp("SIP/MTS-000094a8", "EXTENSION_STATE: 4 (UNKNOWN)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [finish@dialparties-isextavail:2] Return("SIP/MTS-000094a8", "4") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:8] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:9] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTHASCW)=0):Set(HASH(dialparties,EXTHASCW)=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:10] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,EXTHASCW)=0)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:11] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTCFB)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:12] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTHASCFB)=1):Set(HASH(dialparties,EXTHASCFB)=0)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:13] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTCFU)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:14] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTHASCFU)=1):Set(HASH(dialparties,EXTHASCFU)=0)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:15] NoOp("SIP/MTS-000094a8", "extnum 89226556699# has:: cw: 0, hascfb: 0 [], hascfu: 0 []") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:16] GotoIf("SIP/MTS-000094a8", "1?finishmastermodecwchecks") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-mainloop,s,26)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:26] NoOp("SIP/MTS-000094a8", "Extension 89226556699# has ExtensionState: 4") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:27] Gosub("SIP/MTS-000094a8", "dialparties-checkcfextension,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextension:1] GotoIf("SIP/MTS-000094a8", "1?return") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkcfextension,s,6)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextension:6] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:28] NoOp("SIP/MTS-000094a8", "Now working with: 89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:29] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:30] Set("SIP/MTS-000094a8", "HASH(dialparties,REALEXT)=89226556699") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:31] GotoIf("SIP/MTS-000094a8", "0?inhash") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:32] Set("SIP/MTS-000094a8", "HASH(DSARRAY,89226556699)=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:33] Set("SIP/MTS-000094a8", "HASH(DSORDEREDARRAY,0)=89226556699") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:34] Set("SIP/MTS-000094a8", "HASH(DSORDEREDARRAYNOREPLACE,0)=89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:35] Gosub("SIP/MTS-000094a8", "dialparties-getdialstring,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:1] Set("SIP/MTS-000094a8", "HASH(dialparties,DIALSTRING)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:2] GotoIf("SIP/MTS-000094a8", "0?internal,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:3] Set("SIP/MTS-000094a8", "HASH(dialparties,workingext)=89226556699") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:4] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,DIALSTRING)=Local/89226556699@from-internal/n&):Set(HASH(dialparties,DIALSTRING)=Local/RG-*-89226556699#@from-internal&)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:5] NoOp("SIP/MTS-000094a8", "Built External dialstring component for 89226556699: Local/89226556699@from-internal/n&") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:6] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:36] GotoIf("SIP/MTS-000094a8", "0?updatecallerid") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:37] Set("SIP/MTS-000094a8", "HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n&") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:38] Gosub("SIP/MTS-000094a8", "dialparties-call-trace,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:1] GotoIf("SIP/MTS-000094a8", "0?notrace") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:2] ExecIf("SIP/MTS-000094a8", "1?Set(DB(CALLTRACE/89226556699)})=83463259011):Noop(DbDel CALLTRACE/89226556699 - Caller ID is not defined. )") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:3] Goto("SIP/MTS-000094a8", "return") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-call-trace,s,5)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:5] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:39] Goto("SIP/MTS-000094a8", "next") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-mainloop,s,41)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:41] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:42] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:3] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:43] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:65] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:66] NoOp("SIP/MTS-000094a8", "Final DS so far is Local/89226556699@from-internal/n") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:67] GosubIf("SIP/MTS-000094a8", "0?dialparties-firstnotonphone,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:68] Set("SIP/MTS-000094a8", "Z=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:69] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:70] Set("SIP/MTS-000094a8", "FILTERED_DIAL=89226556699-") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:71] ExecIf("SIP/MTS-000094a8", "1?Set(FMGL_DIAL=89226556699#-)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:72] Set("SIP/MTS-000094a8", "Z=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:73] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:69] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:74] ExecIf("SIP/MTS-000094a8", "1?Set(FILTERED_DIAL=89226556699)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:75] ExecIf("SIP/MTS-000094a8", "1?Set(FMGL_DIAL=89226556699#)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:76] NoOp("SIP/MTS-000094a8", "Filtered ARG3: 89226556699") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:77] NoOp("SIP/MTS-000094a8", "RING ALL V2: 89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:78] Set("SIP/MTS-000094a8", "HASH(dialparties,HUNT_LOOPS)=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:79] GosubIf("SIP/MTS-000094a8", "0?dialparties-builddshunt,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:80] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:81] Gosub("SIP/MTS-000094a8", "dialparties-checkblkvm,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:1] GotoIf("SIP/MTS-000094a8", "0?endblkvm") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:2] GotoIf("SIP/MTS-000094a8", "1?addblkvm") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkblkvm,s,5)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:5] Set("SIP/MTS-000094a8", "HASH(dialparties,DIALOPTS)=HhTtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:6] NoOp("SIP/MTS-000094a8", "NODEST: 1097 adding M(auto-blkvm) to dialopts: HhTtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:7] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:82] GosubIf("SIP/MTS-000094a8", "0?dialparties-ringallv2,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:83] Gosub("SIP/MTS-000094a8", "dialparties-checkblkvm,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:1] GotoIf("SIP/MTS-000094a8", "0?endblkvm") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:2] GotoIf("SIP/MTS-000094a8", "0?addblkvm") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:3] NoOp("SIP/MTS-000094a8", "NODEST: 1097 blkvm enabled macro already in dialopts: HhTtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:4] Goto("SIP/MTS-000094a8", "endblkvm") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkblkvm,s,7)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:7] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:84] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,DIALOPTS)=HhtrIM(auto-blkvm))") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:85] GosubIf("SIP/MTS-000094a8", "1?dialparties-finalbuild,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-finalbuild:1] GotoIf("SIP/MTS-000094a8", "0?hunt,1:nohunt,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-finalbuild,nohunt,1)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:1] Set("SIP/MTS-000094a8", "HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:2] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,60)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:3] ExecIf("SIP/MTS-000094a8", "0?Set(__RT=60)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:4] Set("SIP/MTS-000094a8", "HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:5] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm)p)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:6] GotoIf("SIP/MTS-000094a8", "0?useconfirmation,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:7] Set("SIP/MTS-000094a8", "MACRODIALGOTO_PRI=normdial") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:8] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:86] ExecIf("SIP/MTS-000094a8", "0?Set(DIALSTATUS=NOANSWER)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:87] Gosub("SIP/MTS-000094a8", "dialparties-setrvol,s,1()") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:1] Set("SIP/MTS-000094a8", "HASH(dialparties,RVOL_MODE)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:2] Set("SIP/MTS-000094a8", "HASH(dialparties,RVOL)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:3] Set("SIP/MTS-000094a8", "HASH(dialparties,RVOL_PARENT)=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:4] NoOp("SIP/MTS-000094a8", "RVOL_MODE is:  ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:5] NoOp("SIP/MTS-000094a8", "RVOL is: ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:6] NoOp("SIP/MTS-000094a8", "RVOLPARENT is: ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:7] GotoIf("SIP/MTS-000094a8", "0?,1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:8] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:88] ClearHash("SIP/MTS-000094a8", "DSORDEREDARRAYNOREPLACE") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:89] ClearHash("SIP/MTS-000094a8", "DSORDEREDARRAY") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:90] ClearHash("SIP/MTS-000094a8", "DSARRAY") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:91] ClearHash("SIP/MTS-000094a8", "dialparties_FMFMLIST") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:92] ClearHash("SIP/MTS-000094a8", "dialparties_EXTLIST") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:93] ClearHash("SIP/MTS-000094a8", "dialparties_EXT_HUNT") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:94] Set("SIP/MTS-000094a8", "ds=Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:95] Set("SIP/MTS-000094a8", "TIMEOUT=60") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:96] Set("SIP/MTS-000094a8", "DIALOPTS=HhtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:97] NoOp("SIP/MTS-000094a8", "Jumping to Priority: normdial") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:98] ClearHash("SIP/MTS-000094a8", "dialparties") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:99] NoOp("SIP/MTS-000094a8", "End of dialparties") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:100] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:7] GotoIf("SIP/MTS-000094a8", "1?normdial") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-dial,s,10)
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:10] NoOp("SIP/MTS-000094a8", "Returned from dialparties with groups to dial") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:11] NoOp("SIP/MTS-000094a8", "ringall array 89226556699# ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:12] NoOp("SIP/MTS-000094a8", "ds= Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm) ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:13] NoOp("SIP/MTS-000094a8", "dsextra= ") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:14] Set("SIP/MTS-000094a8", "ds=Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:15] NoOp("SIP/MTS-000094a8", "ds= Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:16] Set("SIP/MTS-000094a8", "__FMGL_DIAL=89226556699#") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:17] Set("SIP/MTS-000094a8", "LOOPCNT=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:18] Set("SIP/MTS-000094a8", "ITER=1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:19] Set("SIP/MTS-000094a8", "__EXTTOCALL=89226556699") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:20] NoOp("SIP/MTS-000094a8", "Working with 89226556699") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:21] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:22] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:23] Set("SIP/MTS-000094a8", "ITER=2") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:24] GotoIf("SIP/MTS-000094a8", "0?ndloopbegin") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:25] Macro("SIP/MTS-000094a8", "dial-ringall-predial-hook,") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:26] ExecIf("SIP/MTS-000094a8", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:27] ExecIf("SIP/MTS-000094a8", "0?Set(ds=Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm)g)") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:28] Dial("SIP/MTS-000094a8", "Local/89226556699@from-internal/n,60,HhtrIM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] app_stack.c: Local/89226556699@from-internal-000001b3;1 Internal Gosub(func-apply-sipheaders,s,1) start
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/89226556699@from-internal-000001b3;1", "Applying SIP Headers to channel Local/89226556699@from-internal-000001b3;1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/89226556699@from-internal-000001b3;1", "TECH=Local") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/89226556699@from-internal-000001b3;1", "SIPHEADERKEYS=") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/89226556699@from-internal-000001b3;1", "0") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] app_while.c: Jumping to priority 10
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:11] Return("Local/89226556699@from-internal-000001b3;1", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] app_stack.c: Spawn extension (from-internal, 1097, 1) exited non-zero on 'Local/89226556699@from-internal-000001b3;1'
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] app_stack.c: Local/89226556699@from-internal-000001b3;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] app_dial.c: Called Local/89226556699@from-internal/n
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [89226556699@from-internal:1] Macro("Local/89226556699@from-internal-000001b3;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/89226556699@from-internal-000001b3;2", "TOUCH_MONITOR=1732873140.38928") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/89226556699@from-internal-000001b3;2", "CHANCONTEXT=from-internal-000001b3;2") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:3] Set("Local/89226556699@from-internal-000001b3;2", "CHANCONTEXT=from") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/89226556699@from-internal-000001b3;2", "CHANEXTENCONTEXT=89226556699@from-internal-000001b3;2") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/89226556699@from-internal-000001b3;2", "CHANEXTEN=89226556699") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/89226556699@from-internal-000001b3;2", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/89226556699@from-internal-000001b3;2", "AMPUSER=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/89226556699@from-internal-000001b3;2", "HOTDESCKCHAN=89226556699@from-internal-000001b3;2") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/89226556699@from-internal-000001b3;2", "HOTDESKEXTEN=89226556699@from") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/89226556699@from-internal-000001b3;2", "HOTDESKCALL=0") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("Local/89226556699@from-internal-000001b3;2", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/89226556699@from-internal-000001b3;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("Local/89226556699@from-internal-000001b3;2", "0?report") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("Local/89226556699@from-internal-000001b3;2", "1?Set(REALCALLERIDNUM=83463259011)") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:15] Set("Local/89226556699@from-internal-000001b3;2", "AMPUSER=") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("Local/89226556699@from-internal-000001b3;2", "0?limit") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:17] Set("Local/89226556699@from-internal-000001b3;2", "AMPUSERCIDNAME=") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("Local/89226556699@from-internal-000001b3;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("Local/89226556699@from-internal-000001b3;2", "1?report") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:28] NoOp("Local/89226556699@from-internal-000001b3;2", "Macro Depth is 1") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("Local/89226556699@from-internal-000001b3;2", "1?report2:macroerror") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/89226556699@from-internal-000001b3;2", "1?continue") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:49] Set("Local/89226556699@from-internal-000001b3;2", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/89226556699@from-internal-000001b3;2", "CALLERID(name)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("Local/89226556699@from-internal-000001b3;2", "0?cnum") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:52] Set("Local/89226556699@from-internal-000001b3;2", "CDR(cnam)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:53] Set("Local/89226556699@from-internal-000001b3;2", "CDR(cnum)=83463259011") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-user-callerid:54] Set("Local/89226556699@from-internal-000001b3;2", "CHANNEL(language)=ru") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [89226556699@from-internal:2] NoCDR("Local/89226556699@from-internal-000001b3;2", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [89226556699@from-internal:3] Progress("Local/89226556699@from-internal-000001b3;2", "") in new stack
[2024-11-29 14:39:00] VERBOSE[201556][C-000040ac] pbx.c: Executing [89226556699@from-internal:4] Wait("Local/89226556699@from-internal-000001b3;2", "1") in new stack
[2024-11-29 14:39:00] VERBOSE[201530][C-000040ac] app_dial.c: Local/89226556699@from-internal-000001b3;1 is making progress passing it to SIP/MTS-000094a8
[2024-11-29 14:39:01] VERBOSE[201556][C-000040ac] pbx.c: Executing [89226556699@from-internal:5] Playback("Local/89226556699@from-internal-000001b3;2", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2024-11-29 14:39:01] VERBOSE[201556][C-000040ac] file.c: <Local/89226556699@from-internal-000001b3;2> Playing 'silence/1.ulaw' (language 'ru')
[2024-11-29 14:39:02] VERBOSE[201556][C-000040ac] file.c: <Local/89226556699@from-internal-000001b3;2> Playing 'cannot-complete-as-dialed.ulaw' (language 'ru')
[2024-11-29 14:39:05] VERBOSE[201556][C-000040ac] file.c: <Local/89226556699@from-internal-000001b3;2> Playing 'check-number-dial-again.ulaw' (language 'ru')
[2024-11-29 14:39:08] VERBOSE[201556][C-000040ac] pbx.c: Executing [89226556699@from-internal:6] Wait("Local/89226556699@from-internal-000001b3;2", "1") in new stack
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx.c: Executing [89226556699@from-internal:7] Congestion("Local/89226556699@from-internal-000001b3;2", "20") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] app_dial.c: Local/89226556699@from-internal-000001b3;1 is circuit-busy
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:29] Set("SIP/MTS-000094a8", "DIALSTATUS=CONGESTION") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:30] GosubIf("SIP/MTS-000094a8", "0?CONGESTION,1()") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:31] NoOp("SIP/MTS-000094a8", "Returning since nobody answered") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:32] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:40] Goto("SIP/MTS-000094a8", "nextstep") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,47)
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:47] Set("SIP/MTS-000094a8", "RingGroupMethod=") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:48] GotoIf("SIP/MTS-000094a8", "0?nodest") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:49] Set("SIP/MTS-000094a8", "__NODEST=") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:50] Set("SIP/MTS-000094a8", "__PICKUPMARK=") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:51] Macro("SIP/MTS-000094a8", "blkvm-clr,") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/MTS-000094a8", "SHARED(BLKVM,SIP/MTS-000094a8)=") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-clr:2] Set("SIP/MTS-000094a8", "GOSUB_RETVAL=") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:52] Set("SIP/MTS-000094a8", "DIALSTATUS=NOANSWER") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:53] GotoIf("SIP/MTS-000094a8", "0?dohangup") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:54] Goto("SIP/MTS-000094a8", "ext-local,1097,dest") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ext-local,1097,4)
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ext-local:4] Set("SIP/MTS-000094a8", "__PICKUPMARK=") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ext-local:5] GotoIf("SIP/MTS-000094a8", "1?ivr-1,return,1") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ivr-1,return,1)
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:1] Set("SIP/MTS-000094a8", "_IVR_CONTEXT=ivr-1") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:2] Set("SIP/MTS-000094a8", "_IVR_CONTEXT_ivr-1=") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:3] Set("SIP/MTS-000094a8", "IVR_MSG=custom/Hello_ASU") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:4] Goto("SIP/MTS-000094a8", "s,start") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ivr-1,s,8)
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:8] Set("SIP/MTS-000094a8", "TIMEOUT(digit)=3") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] func_timeout.c: Digit timeout set to 3.000
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:9] ExecIf("SIP/MTS-000094a8", "1?Background(custom/Hello_ASU)") in new stack
[2024-11-29 14:39:09] VERBOSE[201530][C-000040ac] file.c: <SIP/MTS-000094a8> Playing 'custom/Hello_ASU.alaw' (language 'ru')
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx.c: Spawn extension (from-internal, 89226556699, 7) exited non-zero on 'Local/89226556699@from-internal-000001b3;2'
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx.c: Executing [h@from-internal:1] Macro("Local/89226556699@from-internal-000001b3;2", "hangupcall") in new stack
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/89226556699@from-internal-000001b3;2", "1?theend") in new stack
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/89226556699@from-internal-000001b3;2", "0?Set(CDR(recordingfile)=)") in new stack
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx.c: Executing [s@macro-hangupcall:4] Hangup("Local/89226556699@from-internal-000001b3;2", "") in new stack
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/89226556699@from-internal-000001b3;2' in macro 'hangupcall'
[2024-11-29 14:39:09] VERBOSE[201556][C-000040ac] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/89226556699@from-internal-000001b3;2'
[2024-11-29 14:39:17] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:10] WaitExten("SIP/MTS-000094a8", "5,") in new stack
[2024-11-29 14:39:17] DTMF[201530][C-000040ac] channel.c: DTMF begin '1' received on SIP/MTS-000094a8
[2024-11-29 14:39:17] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '1' on SIP/MTS-000094a8
[2024-11-29 14:39:17] DTMF[201530][C-000040ac] channel.c: DTMF end '1' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:17] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '1' on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF begin '0' received on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '0' on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF end '0' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '0' on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF begin '9' received on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '9' on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF end '9' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '9' on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF begin '7' received on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '7' on SIP/MTS-000094a8
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF end '7' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:18] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '7' on SIP/MTS-000094a8
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ivr-1:1] Set("SIP/MTS-000094a8", "__ivrreturn=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ivr-1:2] Goto("SIP/MTS-000094a8", "from-did-direct,1097,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (from-did-direct,1097,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@from-did-direct:1] GotoIf("SIP/MTS-000094a8", "0?ext-local,1097,1:followme-check,1097,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-check,1097,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-check:1] Gosub("SIP/MTS-000094a8", "followme-sub,1097,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:1] GotoIf("SIP/MTS-000094a8", "0?skipclid") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:2] Macro("SIP/MTS-000094a8", "user-callerid,") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/MTS-000094a8", "TOUCH_MONITOR=1732873137.38926") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/MTS-000094a8", "CHANCONTEXT=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/MTS-000094a8", "CHANCONTEXT=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/MTS-000094a8", "CHANEXTENCONTEXT=MTS-000094a8") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/MTS-000094a8", "CHANEXTEN=MTS-000094a8") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:6] Set("SIP/MTS-000094a8", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/MTS-000094a8", "AMPUSER=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:8] Set("SIP/MTS-000094a8", "HOTDESCKCHAN=MTS-000094a8") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/MTS-000094a8", "HOTDESKEXTEN=MTS") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/MTS-000094a8", "HOTDESKCALL=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SIP/MTS-000094a8", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SIP/MTS-000094a8", "0?Set(CALLERID(name)=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/MTS-000094a8", "0?report") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/MTS-000094a8", "0?Set(REALCALLERIDNUM=83463259011)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/MTS-000094a8", "AMPUSER=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/MTS-000094a8", "0?limit") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/MTS-000094a8", "AMPUSERCIDNAME=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/MTS-000094a8", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/MTS-000094a8", "1?report") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/MTS-000094a8", "Macro Depth is 1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/MTS-000094a8", "1?report2:macroerror") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/MTS-000094a8", "0?continue") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/MTS-000094a8", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/MTS-000094a8", "__TTL=63") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/MTS-000094a8", "1?continue") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/MTS-000094a8", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/MTS-000094a8", "CALLERID(name)=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/MTS-000094a8", "0?cnum") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/MTS-000094a8", "CDR(cnam)=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/MTS-000094a8", "CDR(cnum)=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/MTS-000094a8", "CHANNEL(language)=ru") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:3] Set("SIP/MTS-000094a8", "DIAL_OPTIONS=HhTtrII") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:4] Set("SIP/MTS-000094a8", "CONNECTEDLINE(num,i)=1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:5] Gosub("SIP/MTS-000094a8", "sub-presencestate-display,s,1(1097)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-presencestate-display:1] Goto("SIP/MTS-000094a8", "state-available,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-presencestate-display,state-available,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [state-available@sub-presencestate-display:1] Set("SIP/MTS-000094a8", "PRESENCESTATE_DISPLAY=(Available)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [state-available@sub-presencestate-display:2] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:6] Set("SIP/MTS-000094a8", "CONNECTEDLINE(name)=Острожной Д.А.(Available)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:7] Set("SIP/MTS-000094a8", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:8] Set("SIP/MTS-000094a8", "__EXTTOCALL=1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:9] Set("SIP/MTS-000094a8", "__PICKUPMARK=1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:10] Macro("SIP/MTS-000094a8", "blkvm-setifempty,") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/MTS-000094a8", "0?init") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:2] Set("SIP/MTS-000094a8", "GOSUB_RETVAL=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-setifempty:3] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:11] GotoIf("SIP/MTS-000094a8", "0?skipov") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:12] Macro("SIP/MTS-000094a8", "blkvm-set,reset") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-set:1] ExecIf("SIP/MTS-000094a8", "0?Set(__BLKVM_CHANNEL=SIP/MTS-000094a8)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-set:2] Set("SIP/MTS-000094a8", "SHARED(BLKVM,SIP/MTS-000094a8)=TRUE") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-set:3] Set("SIP/MTS-000094a8", "GOSUB_RETVAL=TRUE") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-set:4] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:13] Set("SIP/MTS-000094a8", "__NODEST=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:14] Set("SIP/MTS-000094a8", "RRNODEST=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:15] Set("SIP/MTS-000094a8", "__NODEST=1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:16] GosubIf("SIP/MTS-000094a8", "1?sub-fmsetcid,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-fmsetcid:1] Goto("SIP/MTS-000094a8", "s-fixed,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-fmsetcid,s-fixed,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s-fixed@sub-fmsetcid:1] ExecIf("SIP/MTS-000094a8", "1?Set(__TRUNKCIDOVERRIDE=3466635911)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s-fixed@sub-fmsetcid:2] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:17] GotoIf("SIP/MTS-000094a8", "1?skipprepend") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,19)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:19] Set("SIP/MTS-000094a8", "RecordMethod=Group") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:20] Gosub("SIP/MTS-000094a8", "sub-record-check,s,1(exten,1097,)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/MTS-000094a8", "11?initialized") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,s,10)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/MTS-000094a8", "Recordings initialized") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/MTS-000094a8", "1?Set(ARG3=dontcare)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:12] Set("SIP/MTS-000094a8", "REC_POLICY_MODE_SAVE=NO") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/MTS-000094a8", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/MTS-000094a8", "5?checkaction") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/MTS-000094a8", "1?sub-record-check,exten,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Exten Recording Check between 83463259011 and 1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/MTS-000094a8", "CALLTYPE=external") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/MTS-000094a8", "0?Set(CALLTYPE=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/MTS-000094a8", "CALLEE=dontcare") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/MTS-000094a8", "0?Set(CALLEE=dontcare)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/MTS-000094a8", "1?callee") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/MTS-000094a8", "recordcheck,1(dontcare,external,1097)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Starting recording check against dontcare") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/MTS-000094a8", "dontcare") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:21] GotoIf("SIP/MTS-000094a8", "1?skipdring") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,24)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:24] Set("SIP/MTS-000094a8", "STRATEGY=ringall") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:25] Set("SIP/MTS-000094a8", "__RVOL=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:26] GotoIf("SIP/MTS-000094a8", "0?skipsimple") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:27] GotoIf("SIP/MTS-000094a8", "1 ?skipsimple") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,29)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:29] Set("SIP/MTS-000094a8", "RingGroupMethod=ringall") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:30] Set("SIP/MTS-000094a8", "_FMGRP=1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:31] GotoIf("SIP/MTS-000094a8", "1?DIALGRP") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,35)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:35] ExecIf("SIP/MTS-000094a8", "1?Set(DOPTS=HhTtrII):Set(DOPTS=m(Ring)HhTtII)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:36] Set("SIP/MTS-000094a8", "__ALT_CONFIRM_MSG=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:37] GotoIf("SIP/MTS-000094a8", "0?doconfirm") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:38] GotoIf("SIP/MTS-000094a8", "0?ringallv21") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:39] Macro("SIP/MTS-000094a8", "dial,60,HhTtrII,89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:1] NoOp("SIP/MTS-000094a8", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:2] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:3] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:4] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:5] ExecIf("SIP/MTS-000094a8", "0?Set(CHANNEL(musicclass)=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:6] Gosub("SIP/MTS-000094a8", "dialparties,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:1] NoOp("SIP/MTS-000094a8", "Starting New Dialparties") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:2] ClearHash("SIP/MTS-000094a8", "dialparties_FMFMLIST") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:3] ClearHash("SIP/MTS-000094a8", "dialparties_EXTLIST") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:4] ClearHash("SIP/MTS-000094a8", "dialparties") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:5] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:7] NoOp("SIP/MTS-000094a8", "ARG1: 60") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:8] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=2") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:9] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:7] NoOp("SIP/MTS-000094a8", "ARG2: HhTtrII") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:8] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=3") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:9] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:7] NoOp("SIP/MTS-000094a8", "ARG3: 89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:8] Set("SIP/MTS-000094a8", "HASH(dialparties,ARG_CNT)=4") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:9] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:6] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:10] Set("SIP/MTS-000094a8", "HASH(dialparties,CWINUSEBUSY)=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:11] Set("SIP/MTS-000094a8", "HASH(dialparties,CWIGNORE)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:12] Set("SIP/MTS-000094a8", "HASH(dialparties,CFIGNORE)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:13] Set("SIP/MTS-000094a8", "HASH(dialparties,SIGNORE)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:14] Set("SIP/MTS-000094a8", "HASH(dialparties,AMPUSER)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:15] Set("SIP/MTS-000094a8", "HASH(dialparties,FMGRP)=1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:16] Set("SIP/MTS-000094a8", "HASH(dialparties,NODEST)=1097") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:17] Set("SIP/MTS-000094a8", "HASH(dialparties,CIDNUM)=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:18] Set("SIP/MTS-000094a8", "HASH(dialparties,CIDNAME)=83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:19] NoOp("SIP/MTS-000094a8", "Caller ID name is 83463259011 number is 83463259011") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:20] NoOp("SIP/MTS-000094a8", "CW Ignore is: ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:21] NoOp("SIP/MTS-000094a8", "CF Ignore is: ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:22] NoOp("SIP/MTS-000094a8", "CW IN_USE/BUSY is: 1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:23] Set("SIP/MTS-000094a8", "HASH(dialparties,QUEUEWAIT)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:24] GosubIf("SIP/MTS-000094a8", "0?dialparties-queuewait,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:25] Set("SIP/MTS-000094a8", "__KEEPCID=TRUE") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:26] Set("SIP/MTS-000094a8", "DIALSTATUS_CW=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:27] Set("SIP/MTS-000094a8", "HASH(dialparties,TIMER)=60") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:28] Set("SIP/MTS-000094a8", "HASH(dialparties,DIALOPTS)=HhTtrII") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:29] Set("SIP/MTS-000094a8", "HASH(dialparties,RGMETHOD)=ringall") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:30] Set("SIP/MTS-000094a8", "HASH(dialparties,ALERTINFO)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:31] GosubIf("SIP/MTS-000094a8", "0?dialparties-alertinfo,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:32] GosubIf("SIP/MTS-000094a8", "0?dialparties-setheader,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:33] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,PR_DIALSTATUS)=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:34] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,USE_CONFIRMATION)=FALSE)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:35] Set("SIP/MTS-000094a8", "HASH(dialparties,RINGGROUP_INDEX)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:36] NoOp("SIP/MTS-000094a8", "USE_CONFIRMATION: FALSE") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:37] NoOp("SIP/MTS-000094a8", "RINGGROUP_INDEX: ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:38] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,TIMER)=0)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:39] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,DIALOPTS)=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:40] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,RGMETHOD)=none)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:41] NoOp("SIP/MTS-000094a8", "Methodology of ring is ringall") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:42] Set("SIP/MTS-000094a8", "HASH(dialparties,RECALL_MASTERMODE)=ringall") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:43] Gosub("SIP/MTS-000094a8", "dialparties-setmodes,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setmodes:1] GotoIf("SIP/MTS-000094a8", "0?ringall,1:default,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-setmodes,default,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [default@dialparties-setmodes:1] Set("SIP/MTS-000094a8", "HASH(dialparties,PR_DIALSTATUS)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [default@dialparties-setmodes:2] Set("SIP/MTS-000094a8", "HASH(dialparties,MASTERMODE)=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [default@dialparties-setmodes:3] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:44] ExecIf("SIP/MTS-000094a8", "0?Set(__CWIGNORE=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:45] GosubIf("SIP/MTS-000094a8", "0?dialparties-invalidringmethod,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:46] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:47] Set("SIP/MTS-000094a8", "ARG_CNT=3") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:48] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:49] Set("SIP/MTS-000094a8", "TMPARG=89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:50] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:51] Set("SIP/MTS-000094a8", "HASH(dialparties_EXTLIST,0)=89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:52] NoOp("SIP/MTS-000094a8", "Added extension 89226556699# to extension map") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:53] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:54] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:50] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:55] Set("SIP/MTS-000094a8", "ARG_CNT=4") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:56] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:48] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:57] GosubIf("SIP/MTS-000094a8", "0?dialparties-preringprep,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:58] Set("SIP/MTS-000094a8", "HASH(dialparties,ALREADY_SCREENED)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:59] Set("SIP/MTS-000094a8", "HASH(dialparties,FROM_OUTSIDE)=3466635911") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:60] Set("SIP/MTS-000094a8", "HASH(dialparties,PRIMARY_EXT)=89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:61] GosubIf("SIP/MTS-000094a8", "0?dialparties-callscreencheck,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:62] Gosub("SIP/MTS-000094a8", "dialparties-checkcfextensions,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:1] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:2] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:3] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:4] NoOp("SIP/MTS-000094a8", "Working with extension 89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:5] GotoIf("SIP/MTS-000094a8", "1?skipcf") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkcfextensions,s,32)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:32] NoOp("SIP/MTS-000094a8", "Extension 89226556699# appears to be CF, skipping checks") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:33] Goto("SIP/MTS-000094a8", "next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkcfextensions,s,35)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:35] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:36] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:2] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextensions:37] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:63] Gosub("SIP/MTS-000094a8", "dialparties-checkdndextensions,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:1] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:2] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:3] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:4] NoOp("SIP/MTS-000094a8", "Working with extension 89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:5] GotoIf("SIP/MTS-000094a8", "1?skipdnd") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkdndextensions,s,18)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:18] NoOp("SIP/MTS-000094a8", "Extension 89226556699# appears to be CF, skipping checks") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:19] Goto("SIP/MTS-000094a8", "next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkdndextensions,s,21)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:21] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:22] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:2] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkdndextensions:23] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:64] Gosub("SIP/MTS-000094a8", "dialparties-mainloop,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:1] Set("SIP/MTS-000094a8", "I=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:2] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTLISTCOUNT)=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:3] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:4] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:5] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTLISTCOUNT)=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:6] NoOp("SIP/MTS-000094a8", "Working with extension 89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:7] Gosub("SIP/MTS-000094a8", "dialparties-isextavail,s,1(89226556699#)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-isextavail:1] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTSTATESTATUS)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-isextavail:2] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTSTATE_RESULT)=INUSE_ORIGINATOR):Set(HASH(dialparties,EXTSTATE_RESULT)=UNKNOWN)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-isextavail:3] GotoIf("SIP/MTS-000094a8", "1?UNKNOWN,1:default,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-isextavail,UNKNOWN,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [UNKNOWN@dialparties-isextavail:1] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTSTATESTATUS)=4") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [UNKNOWN@dialparties-isextavail:2] Goto("SIP/MTS-000094a8", "finish,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-isextavail,finish,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [finish@dialparties-isextavail:1] NoOp("SIP/MTS-000094a8", "EXTENSION_STATE: 4 (UNKNOWN)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [finish@dialparties-isextavail:2] Return("SIP/MTS-000094a8", "4") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:8] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:9] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTHASCW)=0):Set(HASH(dialparties,EXTHASCW)=)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:10] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,EXTHASCW)=0)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:11] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTCFB)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:12] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTHASCFB)=1):Set(HASH(dialparties,EXTHASCFB)=0)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:13] Set("SIP/MTS-000094a8", "HASH(dialparties,EXTCFU)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:14] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,EXTHASCFU)=1):Set(HASH(dialparties,EXTHASCFU)=0)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:15] NoOp("SIP/MTS-000094a8", "extnum 89226556699# has:: cw: 0, hascfb: 0 [], hascfu: 0 []") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:16] GotoIf("SIP/MTS-000094a8", "1?finishmastermodecwchecks") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-mainloop,s,26)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:26] NoOp("SIP/MTS-000094a8", "Extension 89226556699# has ExtensionState: 4") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:27] Gosub("SIP/MTS-000094a8", "dialparties-checkcfextension,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextension:1] GotoIf("SIP/MTS-000094a8", "1?return") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkcfextension,s,6)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkcfextension:6] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:28] NoOp("SIP/MTS-000094a8", "Now working with: 89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:29] GotoIf("SIP/MTS-000094a8", "0?next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:30] Set("SIP/MTS-000094a8", "HASH(dialparties,REALEXT)=89226556699") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:31] GotoIf("SIP/MTS-000094a8", "0?inhash") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:32] Set("SIP/MTS-000094a8", "HASH(DSARRAY,89226556699)=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:33] Set("SIP/MTS-000094a8", "HASH(DSORDEREDARRAY,0)=89226556699") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:34] Set("SIP/MTS-000094a8", "HASH(DSORDEREDARRAYNOREPLACE,0)=89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:35] Gosub("SIP/MTS-000094a8", "dialparties-getdialstring,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:1] Set("SIP/MTS-000094a8", "HASH(dialparties,DIALSTRING)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:2] GotoIf("SIP/MTS-000094a8", "0?internal,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:3] Set("SIP/MTS-000094a8", "HASH(dialparties,workingext)=89226556699") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:4] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,DIALSTRING)=Local/89226556699@from-internal/n&):Set(HASH(dialparties,DIALSTRING)=Local/RG-*-89226556699#@from-internal&)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:5] NoOp("SIP/MTS-000094a8", "Built External dialstring component for 89226556699: Local/89226556699@from-internal/n&") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-getdialstring:6] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:36] GotoIf("SIP/MTS-000094a8", "0?updatecallerid") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:37] Set("SIP/MTS-000094a8", "HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n&") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:38] Gosub("SIP/MTS-000094a8", "dialparties-call-trace,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:1] GotoIf("SIP/MTS-000094a8", "0?notrace") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:2] ExecIf("SIP/MTS-000094a8", "1?Set(DB(CALLTRACE/89226556699)})=83463259011):Noop(DbDel CALLTRACE/89226556699 - Caller ID is not defined. )") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:3] Goto("SIP/MTS-000094a8", "return") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-call-trace,s,5)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-call-trace:5] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:39] Goto("SIP/MTS-000094a8", "next") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-mainloop,s,41)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:41] Set("SIP/MTS-000094a8", "I=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:42] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:3] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-mainloop:43] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:65] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:66] NoOp("SIP/MTS-000094a8", "Final DS so far is Local/89226556699@from-internal/n") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:67] GosubIf("SIP/MTS-000094a8", "0?dialparties-firstnotonphone,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:68] Set("SIP/MTS-000094a8", "Z=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:69] While("SIP/MTS-000094a8", "1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:70] Set("SIP/MTS-000094a8", "FILTERED_DIAL=8922655669989226556699-") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:71] ExecIf("SIP/MTS-000094a8", "1?Set(FMGL_DIAL=89226556699#89226556699#-)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:72] Set("SIP/MTS-000094a8", "Z=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:73] EndWhile("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:69] While("SIP/MTS-000094a8", "0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:74] ExecIf("SIP/MTS-000094a8", "1?Set(FILTERED_DIAL=8922655669989226556699)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:75] ExecIf("SIP/MTS-000094a8", "1?Set(FMGL_DIAL=89226556699#89226556699#)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:76] NoOp("SIP/MTS-000094a8", "Filtered ARG3: 8922655669989226556699") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:77] NoOp("SIP/MTS-000094a8", "RING ALL V2: 89226556699#89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:78] Set("SIP/MTS-000094a8", "HASH(dialparties,HUNT_LOOPS)=0") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:79] GosubIf("SIP/MTS-000094a8", "0?dialparties-builddshunt,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:80] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:81] Gosub("SIP/MTS-000094a8", "dialparties-checkblkvm,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:1] GotoIf("SIP/MTS-000094a8", "0?endblkvm") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:2] GotoIf("SIP/MTS-000094a8", "1?addblkvm") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkblkvm,s,5)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:5] Set("SIP/MTS-000094a8", "HASH(dialparties,DIALOPTS)=HhTtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:6] NoOp("SIP/MTS-000094a8", "NODEST: 1097 adding M(auto-blkvm) to dialopts: HhTtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:7] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:82] GosubIf("SIP/MTS-000094a8", "0?dialparties-ringallv2,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:83] Gosub("SIP/MTS-000094a8", "dialparties-checkblkvm,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:1] GotoIf("SIP/MTS-000094a8", "0?endblkvm") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:2] GotoIf("SIP/MTS-000094a8", "0?addblkvm") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:3] NoOp("SIP/MTS-000094a8", "NODEST: 1097 blkvm enabled macro already in dialopts: HhTtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:4] Goto("SIP/MTS-000094a8", "endblkvm") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-checkblkvm,s,7)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-checkblkvm:7] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:84] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,DIALOPTS)=HhtrIIM(auto-blkvm))") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:85] GosubIf("SIP/MTS-000094a8", "1?dialparties-finalbuild,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-finalbuild:1] GotoIf("SIP/MTS-000094a8", "0?hunt,1:nohunt,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (dialparties-finalbuild,nohunt,1)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:1] Set("SIP/MTS-000094a8", "HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:2] ExecIf("SIP/MTS-000094a8", "1?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,60)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:3] ExecIf("SIP/MTS-000094a8", "0?Set(__RT=60)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:4] Set("SIP/MTS-000094a8", "HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:5] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(dialparties,FINALDS)=Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm)p)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:6] GotoIf("SIP/MTS-000094a8", "0?useconfirmation,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:7] Set("SIP/MTS-000094a8", "MACRODIALGOTO_PRI=normdial") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [nohunt@dialparties-finalbuild:8] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:86] ExecIf("SIP/MTS-000094a8", "0?Set(DIALSTATUS=NOANSWER)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:87] Gosub("SIP/MTS-000094a8", "dialparties-setrvol,s,1()") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:1] Set("SIP/MTS-000094a8", "HASH(dialparties,RVOL_MODE)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:2] Set("SIP/MTS-000094a8", "HASH(dialparties,RVOL)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:3] Set("SIP/MTS-000094a8", "HASH(dialparties,RVOL_PARENT)=") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:4] NoOp("SIP/MTS-000094a8", "RVOL_MODE is:  ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:5] NoOp("SIP/MTS-000094a8", "RVOL is: ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:6] NoOp("SIP/MTS-000094a8", "RVOLPARENT is: ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:7] GotoIf("SIP/MTS-000094a8", "0?,1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties-setrvol:8] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:88] ClearHash("SIP/MTS-000094a8", "DSORDEREDARRAYNOREPLACE") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:89] ClearHash("SIP/MTS-000094a8", "DSORDEREDARRAY") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:90] ClearHash("SIP/MTS-000094a8", "DSARRAY") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:91] ClearHash("SIP/MTS-000094a8", "dialparties_FMFMLIST") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:92] ClearHash("SIP/MTS-000094a8", "dialparties_EXTLIST") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:93] ClearHash("SIP/MTS-000094a8", "dialparties_EXT_HUNT") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:94] Set("SIP/MTS-000094a8", "ds=Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:95] Set("SIP/MTS-000094a8", "TIMEOUT=60") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:96] Set("SIP/MTS-000094a8", "DIALOPTS=HhtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:97] NoOp("SIP/MTS-000094a8", "Jumping to Priority: normdial") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:98] ClearHash("SIP/MTS-000094a8", "dialparties") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:99] NoOp("SIP/MTS-000094a8", "End of dialparties") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@dialparties:100] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:7] GotoIf("SIP/MTS-000094a8", "1?normdial") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-dial,s,10)
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:10] NoOp("SIP/MTS-000094a8", "Returned from dialparties with groups to dial") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:11] NoOp("SIP/MTS-000094a8", "ringall array 89226556699#89226556699# ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:12] NoOp("SIP/MTS-000094a8", "ds= Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm) ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:13] NoOp("SIP/MTS-000094a8", "dsextra= ") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:14] Set("SIP/MTS-000094a8", "ds=Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:15] NoOp("SIP/MTS-000094a8", "ds= Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm)") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:16] Set("SIP/MTS-000094a8", "__FMGL_DIAL=89226556699#89226556699#") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:17] Set("SIP/MTS-000094a8", "LOOPCNT=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:18] Set("SIP/MTS-000094a8", "ITER=1") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:19] Set("SIP/MTS-000094a8", "__EXTTOCALL=8922655669989226556699") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:20] NoOp("SIP/MTS-000094a8", "Working with 8922655669989226556699") in new stack
[2024-11-29 14:39:18] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:21] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:22] ExecIf("SIP/MTS-000094a8", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:23] Set("SIP/MTS-000094a8", "ITER=2") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:24] GotoIf("SIP/MTS-000094a8", "0?ndloopbegin") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:25] Macro("SIP/MTS-000094a8", "dial-ringall-predial-hook,") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:26] ExecIf("SIP/MTS-000094a8", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:27] ExecIf("SIP/MTS-000094a8", "0?Set(ds=Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm)g)") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:28] Dial("SIP/MTS-000094a8", "Local/89226556699@from-internal/n,60,HhtrIIM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] app_stack.c: Local/89226556699@from-internal-000001b4;1 Internal Gosub(func-apply-sipheaders,s,1) start
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/89226556699@from-internal-000001b4;1", "Applying SIP Headers to channel Local/89226556699@from-internal-000001b4;1") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/89226556699@from-internal-000001b4;1", "TECH=Local") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/89226556699@from-internal-000001b4;1", "SIPHEADERKEYS=") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/89226556699@from-internal-000001b4;1", "0") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] app_while.c: Jumping to priority 10
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:11] Return("Local/89226556699@from-internal-000001b4;1", "") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] app_stack.c: Spawn extension (from-internal, 1097, 1) exited non-zero on 'Local/89226556699@from-internal-000001b4;1'
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] app_stack.c: Local/89226556699@from-internal-000001b4;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] app_dial.c: Called Local/89226556699@from-internal/n
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [89226556699@from-internal:1] Macro("Local/89226556699@from-internal-000001b4;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/89226556699@from-internal-000001b4;2", "TOUCH_MONITOR=1732873159.38930") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/89226556699@from-internal-000001b4;2", "CHANCONTEXT=from-internal-000001b4;2") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:3] Set("Local/89226556699@from-internal-000001b4;2", "CHANCONTEXT=from") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/89226556699@from-internal-000001b4;2", "CHANEXTENCONTEXT=89226556699@from-internal-000001b4;2") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/89226556699@from-internal-000001b4;2", "CHANEXTEN=89226556699") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/89226556699@from-internal-000001b4;2", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/89226556699@from-internal-000001b4;2", "AMPUSER=83463259011") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/89226556699@from-internal-000001b4;2", "HOTDESCKCHAN=89226556699@from-internal-000001b4;2") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/89226556699@from-internal-000001b4;2", "HOTDESKEXTEN=89226556699@from") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/89226556699@from-internal-000001b4;2", "HOTDESKCALL=0") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("Local/89226556699@from-internal-000001b4;2", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/89226556699@from-internal-000001b4;2", "0?Set(CALLERID(name)=)") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("Local/89226556699@from-internal-000001b4;2", "0?report") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("Local/89226556699@from-internal-000001b4;2", "1?Set(REALCALLERIDNUM=83463259011)") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:15] Set("Local/89226556699@from-internal-000001b4;2", "AMPUSER=") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("Local/89226556699@from-internal-000001b4;2", "0?limit") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:17] Set("Local/89226556699@from-internal-000001b4;2", "AMPUSERCIDNAME=") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("Local/89226556699@from-internal-000001b4;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("Local/89226556699@from-internal-000001b4;2", "1?report") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:28] NoOp("Local/89226556699@from-internal-000001b4;2", "Macro Depth is 1") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("Local/89226556699@from-internal-000001b4;2", "1?report2:macroerror") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/89226556699@from-internal-000001b4;2", "1?continue") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:49] Set("Local/89226556699@from-internal-000001b4;2", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/89226556699@from-internal-000001b4;2", "CALLERID(name)=83463259011") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("Local/89226556699@from-internal-000001b4;2", "0?cnum") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:52] Set("Local/89226556699@from-internal-000001b4;2", "CDR(cnam)=83463259011") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:53] Set("Local/89226556699@from-internal-000001b4;2", "CDR(cnum)=83463259011") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-user-callerid:54] Set("Local/89226556699@from-internal-000001b4;2", "CHANNEL(language)=ru") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [89226556699@from-internal:2] NoCDR("Local/89226556699@from-internal-000001b4;2", "") in new stack
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [89226556699@from-internal:3] Progress("Local/89226556699@from-internal-000001b4;2", "") in new stack
[2024-11-29 14:39:19] VERBOSE[201530][C-000040ac] app_dial.c: Local/89226556699@from-internal-000001b4;1 is making progress passing it to SIP/MTS-000094a8
[2024-11-29 14:39:19] VERBOSE[201737][C-000040ac] pbx.c: Executing [89226556699@from-internal:4] Wait("Local/89226556699@from-internal-000001b4;2", "1") in new stack
[2024-11-29 14:39:20] VERBOSE[201737][C-000040ac] pbx.c: Executing [89226556699@from-internal:5] Playback("Local/89226556699@from-internal-000001b4;2", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2024-11-29 14:39:20] VERBOSE[201737][C-000040ac] file.c: <Local/89226556699@from-internal-000001b4;2> Playing 'silence/1.ulaw' (language 'ru')
[2024-11-29 14:39:23] VERBOSE[201737][C-000040ac] file.c: <Local/89226556699@from-internal-000001b4;2> Playing 'check-number-dial-again.ulaw' (language 'ru')
[2024-11-29 14:39:23] VERBOSE[201788] asterisk.c: Remote UNIX connection disconnected
[2024-11-29 14:39:26] VERBOSE[201737][C-000040ac] pbx.c: Executing [89226556699@from-internal:6] Wait("Local/89226556699@from-internal-000001b4;2", "1") in new stack
[2024-11-29 14:39:27] VERBOSE[201808] asterisk.c: Remote UNIX connection disconnected
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx.c: Executing [89226556699@from-internal:7] Congestion("Local/89226556699@from-internal-000001b4;2", "20") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] app_dial.c: Local/89226556699@from-internal-000001b4;1 is circuit-busy
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:29] Set("SIP/MTS-000094a8", "DIALSTATUS=CONGESTION") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:30] GosubIf("SIP/MTS-000094a8", "0?CONGESTION,1()") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:31] NoOp("SIP/MTS-000094a8", "Returning since nobody answered") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial:32] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:40] Goto("SIP/MTS-000094a8", "nextstep") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (followme-sub,1097,47)
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:47] Set("SIP/MTS-000094a8", "RingGroupMethod=") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:48] GotoIf("SIP/MTS-000094a8", "0?nodest") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:49] Set("SIP/MTS-000094a8", "__NODEST=") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:50] Set("SIP/MTS-000094a8", "__PICKUPMARK=") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:51] Macro("SIP/MTS-000094a8", "blkvm-clr,") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/MTS-000094a8", "SHARED(BLKVM,SIP/MTS-000094a8)=") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-clr:2] Set("SIP/MTS-000094a8", "GOSUB_RETVAL=") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:52] Set("SIP/MTS-000094a8", "DIALSTATUS=NOANSWER") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:53] GotoIf("SIP/MTS-000094a8", "0?dohangup") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@followme-sub:54] Goto("SIP/MTS-000094a8", "ext-local,1097,dest") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ext-local,1097,4)
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ext-local:4] Set("SIP/MTS-000094a8", "__PICKUPMARK=") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [1097@ext-local:5] GotoIf("SIP/MTS-000094a8", "1?ivr-1,return,1") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ivr-1,return,1)
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:1] Set("SIP/MTS-000094a8", "_IVR_CONTEXT=ivr-1") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:2] Set("SIP/MTS-000094a8", "_IVR_CONTEXT_ivr-1=") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:3] Set("SIP/MTS-000094a8", "IVR_MSG=custom/Hello_ASU") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [return@ivr-1:4] Goto("SIP/MTS-000094a8", "s,start") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ivr-1,s,8)
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:8] Set("SIP/MTS-000094a8", "TIMEOUT(digit)=3") in new stack
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx.c: Spawn extension (from-internal, 89226556699, 7) exited non-zero on 'Local/89226556699@from-internal-000001b4;2'
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx.c: Executing [h@from-internal:1] Macro("Local/89226556699@from-internal-000001b4;2", "hangupcall") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] func_timeout.c: Digit timeout set to 3.000
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@ivr-1:9] ExecIf("SIP/MTS-000094a8", "1?Background(custom/Hello_ASU)") in new stack
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/89226556699@from-internal-000001b4;2", "1?theend") in new stack
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/89226556699@from-internal-000001b4;2", "0?Set(CDR(recordingfile)=)") in new stack
[2024-11-29 14:39:27] VERBOSE[201530][C-000040ac] file.c: <SIP/MTS-000094a8> Playing 'custom/Hello_ASU.alaw' (language 'ru')
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx.c: Executing [s@macro-hangupcall:4] Hangup("Local/89226556699@from-internal-000001b4;2", "") in new stack
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/89226556699@from-internal-000001b4;2' in macro 'hangupcall'
[2024-11-29 14:39:27] VERBOSE[201737][C-000040ac] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/89226556699@from-internal-000001b4;2'
[2024-11-29 14:39:29] DTMF[201530][C-000040ac] channel.c: DTMF begin '1' received on SIP/MTS-000094a8
[2024-11-29 14:39:29] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '1' on SIP/MTS-000094a8
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF end '1' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '1' on SIP/MTS-000094a8
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF begin '0' received on SIP/MTS-000094a8
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '0' on SIP/MTS-000094a8
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF end '0' received on SIP/MTS-000094a8, duration 76 ms
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '0' on SIP/MTS-000094a8
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF begin '9' received on SIP/MTS-000094a8
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '9' on SIP/MTS-000094a8
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF end '9' received on SIP/MTS-000094a8, duration 89 ms
[2024-11-29 14:39:30] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '9' on SIP/MTS-000094a8
[2024-11-29 14:39:31] DTMF[201530][C-000040ac] channel.c: DTMF begin '5' received on SIP/MTS-000094a8
[2024-11-29 14:39:31] DTMF[201530][C-000040ac] channel.c: DTMF begin ignored '5' on SIP/MTS-000094a8
[2024-11-29 14:39:31] DTMF[201530][C-000040ac] channel.c: DTMF end '5' received on SIP/MTS-000094a8, duration 76 ms
[2024-11-29 14:39:31] DTMF[201530][C-000040ac] channel.c: DTMF end passthrough '5' on SIP/MTS-000094a8
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [1095@ivr-1:1] Set("SIP/MTS-000094a8", "__ivrreturn=0") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [1095@ivr-1:2] Goto("SIP/MTS-000094a8", "from-did-direct,1095,1") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (from-did-direct,1095,1)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [1095@from-did-direct:1] GotoIf("SIP/MTS-000094a8", "1?ext-local,1095,1:followme-check,1095,1") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (ext-local,1095,1)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [1095@ext-local:1] Set("SIP/MTS-000094a8", "__RINGTIMER=15") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [1095@ext-local:2] ExecIf("SIP/MTS-000094a8", "0?Set(__CWIGNORE=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [1095@ext-local:3] Macro("SIP/MTS-000094a8", "exten-vm,novm,1095,0,0,0") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/MTS-000094a8", "user-callerid,") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/MTS-000094a8", "TOUCH_MONITOR=1732873137.38926") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/MTS-000094a8", "CHANCONTEXT=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/MTS-000094a8", "CHANCONTEXT=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/MTS-000094a8", "CHANEXTENCONTEXT=MTS-000094a8") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/MTS-000094a8", "CHANEXTEN=MTS-000094a8") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:6] Set("SIP/MTS-000094a8", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/MTS-000094a8", "AMPUSER=83463259011") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:8] Set("SIP/MTS-000094a8", "HOTDESCKCHAN=MTS-000094a8") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/MTS-000094a8", "HOTDESKEXTEN=MTS") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/MTS-000094a8", "HOTDESKCALL=0") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SIP/MTS-000094a8", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SIP/MTS-000094a8", "0?Set(CALLERID(name)=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/MTS-000094a8", "0?report") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/MTS-000094a8", "0?Set(REALCALLERIDNUM=83463259011)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/MTS-000094a8", "AMPUSER=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/MTS-000094a8", "0?limit") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/MTS-000094a8", "AMPUSERCIDNAME=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/MTS-000094a8", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/MTS-000094a8", "1?report") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/MTS-000094a8", "Macro Depth is 2") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/MTS-000094a8", "1?report2:macroerror") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/MTS-000094a8", "0?continue") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/MTS-000094a8", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/MTS-000094a8", "__TTL=62") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/MTS-000094a8", "1?continue") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/MTS-000094a8", "CALLERID(number)=83463259011") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/MTS-000094a8", "CALLERID(name)=83463259011") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/MTS-000094a8", "0?cnum") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/MTS-000094a8", "CDR(cnam)=83463259011") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/MTS-000094a8", "CDR(cnum)=83463259011") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/MTS-000094a8", "CHANNEL(language)=ru") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/MTS-000094a8", "RingGroupMethod=none") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/MTS-000094a8", "__EXTTOCALL=1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/MTS-000094a8", "__PICKUPMARK=1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/MTS-000094a8", "RT=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:6] GotoIf("SIP/MTS-000094a8", "1?startcheck:exitcheck") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-exten-vm,s,7)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("SIP/MTS-000094a8", "1?featureSIP:featurePJSIP") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-exten-vm,s,8)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("SIP/MTS-000094a8", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("SIP/MTS-000094a8", "0?MacroExit()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:10] GotoIf("SIP/MTS-000094a8", "1?checkfeaturemoni:featurePJSIP") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-exten-vm,s,13)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:13] GotoIf("SIP/MTS-000094a8", "0?featuremoniPJSIP:featuremoniSIP") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-exten-vm,s,17)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("SIP/MTS-000094a8", "0?ChanSpy(SIP/1095,q)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:18] ExecIf("SIP/MTS-000094a8", "0?MacroExit()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("SIP/MTS-000094a8", "1?ext-intercomSIP:ext-intercomPJSIP") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-exten-vm,s,23)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:23] ExecIf("SIP/MTS-000094a8", "0?Gosub(ext-intercom,*801095,1())") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:24] ExecIf("SIP/MTS-000094a8", "0?MacroExit()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:25] NoOp("SIP/MTS-000094a8", "Exiting Checks") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:26] Gosub("SIP/MTS-000094a8", "sub-record-check,s,1(exten,1095,dontcare)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/MTS-000094a8", "11?initialized") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,s,10)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/MTS-000094a8", "Recordings initialized") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/MTS-000094a8", "0?Set(ARG3=dontcare)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:12] Set("SIP/MTS-000094a8", "REC_POLICY_MODE_SAVE=NO") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/MTS-000094a8", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/MTS-000094a8", "5?checkaction") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/MTS-000094a8", "1?sub-record-check,exten,1") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Exten Recording Check between 83463259011 and 1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/MTS-000094a8", "CALLTYPE=external") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/MTS-000094a8", "0?Set(CALLTYPE=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/MTS-000094a8", "CALLEE=dontcare") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/MTS-000094a8", "0?Set(CALLEE=dontcare)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/MTS-000094a8", "1?callee") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/MTS-000094a8", "recordcheck,1(dontcare,external,1095)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/MTS-000094a8", "Starting recording check against dontcare") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/MTS-000094a8", "dontcare") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:27] GotoIf("SIP/MTS-000094a8", "1?macrodial") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-exten-vm,s,33)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:33] GosubIf("SIP/MTS-000094a8", "0?clrheader,1()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-exten-vm:34] Macro("SIP/MTS-000094a8", "dial-one,,HhTtrII,1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/MTS-000094a8", "DEXTEN=1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:2] ExecIf("SIP/MTS-000094a8", "0?Set(__EXTTOCALL=1095)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:3] Set("SIP/MTS-000094a8", "DIALSTATUS_CW=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:4] GosubIf("SIP/MTS-000094a8", "0?screen,1()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:5] GosubIf("SIP/MTS-000094a8", "0?cf,1()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:6] GotoIf("SIP/MTS-000094a8", "1?skip1") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-dial-one,s,9)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:9] GotoIf("SIP/MTS-000094a8", "0?nodial") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/MTS-000094a8", "0?continue") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:11] Set("SIP/MTS-000094a8", "EXTHASCW=ENABLED") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:12] GotoIf("SIP/MTS-000094a8", "0?next1:cwinusebusy") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-dial-one,s,24)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:24] GotoIf("SIP/MTS-000094a8", "0?next3:continue") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-dial-one,s,26)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:26] GotoIf("SIP/MTS-000094a8", "0?nodial") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:27] GosubIf("SIP/MTS-000094a8", "1?dstring,1():dlocal,1()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/MTS-000094a8", "DSTRING=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/MTS-000094a8", "DEVICES=1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/MTS-000094a8", "0?Return()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/MTS-000094a8", "0?Set(DEVICES=095)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/MTS-000094a8", "LOOPCNT=1") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/MTS-000094a8", "ITER=1") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/MTS-000094a8", "THISDIAL=SIP/1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("SIP/MTS-000094a8", "1?docheck") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-dial-one,dstring,14)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("SIP/MTS-000094a8", "0?skipset") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:15] Set("SIP/MTS-000094a8", "DSTRING=SIP/1095&") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/MTS-000094a8", "ITER=2") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("SIP/MTS-000094a8", "0?begin") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("SIP/MTS-000094a8", "0?Return()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:19] Set("SIP/MTS-000094a8", "DSTRING=SIP/1095") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [dstring@macro-dial-one:20] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/MTS-000094a8", "0?nodial") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:29] GotoIf("SIP/MTS-000094a8", "0?skiptrace") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:30] GosubIf("SIP/MTS-000094a8", "1?ctset,1():ctclear,1()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/MTS-000094a8", "DB(CALLTRACE/1095)=83463259011") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:31] Set("SIP/MTS-000094a8", "D_OPTIONS=HhTtrII") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:32] GosubIf("SIP/MTS-000094a8", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:33] NoOp("SIP/MTS-000094a8", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:34] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:35] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/MTS-000094a8", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:39] GosubIf("SIP/MTS-000094a8", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:40] ExecIf("SIP/MTS-000094a8", "0?Set(CHANNEL(musicclass)=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:41] GosubIf("SIP/MTS-000094a8", "0?qwait,1()") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:42] Set("SIP/MTS-000094a8", "__CWIGNORE=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:43] Set("SIP/MTS-000094a8", "__KEEPCID=TRUE") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:44] GotoIf("SIP/MTS-000094a8", "0?usegoto,1") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:45] GotoIf("SIP/MTS-000094a8", "1?godial") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx_builtins.c: Goto (macro-dial-one,s,50)
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:50] Macro("SIP/MTS-000094a8", "dialout-one-predial-hook,") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:51] ExecIf("SIP/MTS-000094a8", "1?Set(D_OPTIONS=HhtrIII)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:52] ExecIf("SIP/MTS-000094a8", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:53] NoOp("SIP/MTS-000094a8", "") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:54] ExecIf("SIP/MTS-000094a8", "0?Set(D_OPTIONS=HhtrIII)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:55] ExecIf("SIP/MTS-000094a8", "0?Set(D_OPTIONS=HhtrIIIg)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@macro-dial-one:56] Dial("SIP/MTS-000094a8", "SIP/1095,,HhtrIIIb(func-apply-sipheaders^s^1)") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] netsock2.c: Using SIP RTP TOS bits 184
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] netsock2.c: Using SIP RTP CoS mark 5
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] app_stack.c: SIP/1095-000094aa Internal Gosub(func-apply-sipheaders,s,1) start
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/1095-000094aa", "Applying SIP Headers to channel SIP/1095-000094aa") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/1095-000094aa", "TECH=SIP") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/1095-000094aa", "SIPHEADERKEYS=") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:4] While("SIP/1095-000094aa", "0") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] app_while.c: Jumping to priority 10
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] pbx.c: Executing [s@func-apply-sipheaders:11] Return("SIP/1095-000094aa", "") in new stack
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] app_stack.c: Spawn extension (from-internal, 1095, 1) exited non-zero on 'SIP/1095-000094aa'
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] app_stack.c: SIP/1095-000094aa Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] app_dial.c: Called SIP/1095
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] app_dial.c: Connected line update to SIP/MTS-000094a8 prevented.
[2024-11-29 14:39:31] VERBOSE[201530][C-000040ac] app_dial.c: SIP/1095-000094aa is ringing