Text to speech not working, what do I do wrong?

Hi guys/girls,

I would like to use text to speech but somehow it’s not working.
This is what I did:
I go to Aplications --> Text to speech
I click on + Add TTS
I fill in the name and the text I want to hear.
Flite is the selected (and only available) engine
I select the destination where the caller needs to go after hearing the message.

When calling, the TTS is skipped and caller goes to the selected destination

Any ideas?

Provide a call trace via pastebin so we can see what the dialplan is doing
https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs-PartII

Here you go, hope you can make any sense out of it, I can’t :wink:
It’s in two parts because there is a limit I can post. This is part one:

[2021-11-10 16:28:43] VERBOSE[2828][C-00001130] netsock2.c: Using SIP VIDEO TOS bits 136
[2021-11-10 16:28:43] VERBOSE[2828][C-00001130] netsock2.c: Using SIP VIDEO CoS mark 6
[2021-11-10 16:28:43] VERBOSE[2828][C-00001130] netsock2.c: Using SIP RTP TOS bits 184
[2021-11-10 16:28:43] VERBOSE[2828][C-00001130] netsock2.c: Using SIP RTP CoS mark 5
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:1] Set("SIP/Cheapconnect-00000f32", "__DIRECTION=INBOUND") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:2] Gosub("SIP/Cheapconnect-00000f32", "sub-record-check,s,1(in,31853018317,dontcare)") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/Cheapconnect-00000f32", "0?initialized") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:2] Set("SIP/Cheapconnect-00000f32", "__REC_STATUS=INITIALIZED") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:3] Set("SIP/Cheapconnect-00000f32", "NOW=1636558123") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:4] Set("SIP/Cheapconnect-00000f32", "__DAY=10") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:5] Set("SIP/Cheapconnect-00000f32", "__MONTH=11") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:6] Set("SIP/Cheapconnect-00000f32", "__YEAR=2021") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:7] Set("SIP/Cheapconnect-00000f32", "__TIMESTR=20211110-162843") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:8] Set("SIP/Cheapconnect-00000f32", "__FROMEXTEN=unknown") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:9] Set("SIP/Cheapconnect-00000f32", "__MON_FMT=wav") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/Cheapconnect-00000f32", "Recordings initialized") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(ARG3=dontcare)") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:12] Set("SIP/Cheapconnect-00000f32", "REC_POLICY_MODE_SAVE=") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(REC_STATUS=NO)") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/Cheapconnect-00000f32", "2?checkaction") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,s,17)
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/Cheapconnect-00000f32", "1?sub-record-check,in,1") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,in,1)
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/Cheapconnect-00000f32", "Inbound Recording Check to 31853018317") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [in@sub-record-check:2] Set("SIP/Cheapconnect-00000f32", "FROMEXTEN=unknown") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/Cheapconnect-00000f32", "10?Set(FROMEXTEN=XXXXXXXXXX)") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/Cheapconnect-00000f32", "recordcheck,1(dontcare,in,31853018317)") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/Cheapconnect-00000f32", "Starting recording check against dontcare") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/Cheapconnect-00000f32", "dontcare") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [in@sub-record-check:5] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:3] Set("SIP/Cheapconnect-00000f32", "CHANNEL(tonezone)=nl") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:4] Set("SIP/Cheapconnect-00000f32", "__FROM_DID=31853018317") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:5] Set("SIP/Cheapconnect-00000f32", "returnhere=1") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:6] Gosub("SIP/Cheapconnect-00000f32", "app-blacklist-check,s,1()") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/Cheapconnect-00000f32", "0?blacklisted") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/Cheapconnect-00000f32", "CALLED_BLACKLIST=1") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:7] Set("SIP/Cheapconnect-00000f32", "CDR(did)=31853018317") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:8] GotoIf("SIP/Cheapconnect-00000f32", "0?") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:9] ExecIf("SIP/Cheapconnect-00000f32", "1 ?Set(CALLERID(name)=XXXXXXXXXX)") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:10] Set("SIP/Cheapconnect-00000f32", "__MOHCLASS=") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:11] Set("SIP/Cheapconnect-00000f32", "__REVERSAL_REJECT=FALSE") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:12] GotoIf("SIP/Cheapconnect-00000f32", "1?post-reverse-charge") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (from-trunk,31853018317,14)
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:14] NoOp("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:15] Set("SIP/Cheapconnect-00000f32", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:16] Set("SIP/Cheapconnect-00000f32", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:17] Set("SIP/Cheapconnect-00000f32", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:18] Set("SIP/Cheapconnect-00000f32", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:19] NoOp("SIP/Cheapconnect-00000f32", "CallerID Entry Point") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:20] Set("SIP/Cheapconnect-00000f32", "__CRM_DIRECTION=INBOUND") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:21] Set("SIP/Cheapconnect-00000f32", "__CRM_SOURCE=XXXXXXXXXX") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:22] Set("SIP/Cheapconnect-00000f32", "__CRM_LINKEDID=1636558123.5639") in new stack
[2021-11-10 16:28:43] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:23] AGI("SIP/Cheapconnect-00000f32", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
[2021-11-10 16:28:44] VERBOSE[4562][C-00001130] res_agi.c: <SIP/Cheapconnect-00000f32>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2021-11-10 16:28:44] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:24] ExecIf("SIP/Cheapconnect-00000f32", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2021-11-10 16:28:44] VERBOSE[4562][C-00001130] pbx.c: Executing [31853018317@from-trunk:25] Goto("SIP/Cheapconnect-00000f32", "ext-tts,2,1") in new stack
[2021-11-10 16:28:44] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (ext-tts,2,1)
[2021-11-10 16:28:44] VERBOSE[4562][C-00001130] pbx.c: Executing [2@ext-tts:1] NoOp("SIP/Cheapconnect-00000f32", "TTS: Test") in new stack
[2021-11-10 16:28:44] VERBOSE[4562][C-00001130] pbx.c: Executing [2@ext-tts:2] NoOp("SIP/Cheapconnect-00000f32", "Using: flite") in new stack
[2021-11-10 16:28:44] VERBOSE[4562][C-00001130] pbx.c: Executing [2@ext-tts:3] Answer("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [2@ext-tts:4] AGI("SIP/Cheapconnect-00000f32", "agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite: TTS AGI Started
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite: Generated WAV file: /var/lib/asterisk/sounds/tts/flite-tts-c6916d3fb0ec694bbc1c0c268dd97775.sln
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite: TXT file: /var/lib/asterisk/sounds/tts/flite-tts-c6916d3fb0ec694bbc1c0c268dd97775.txt
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite: Text to speech wave file doesnt exist, lets create it.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite: Executing flite
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite: File was not created!
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: agi://127.0.0.1/propolys-tts.agi,"Hello, this is a text to speech message. Can you hear it?",flite,/usr/sbin/flite: TTS AGI end
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] res_agi.c: <SIP/Cheapconnect-00000f32>AGI Script agi://127.0.0.1/propolys-tts.agi completed, returning 0
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [2@ext-tts:5] Goto("SIP/Cheapconnect-00000f32", "from-did-direct,2003,1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (from-did-direct,2003,1)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [2003@from-did-direct:1] GotoIf("SIP/Cheapconnect-00000f32", "1?ext-local,2003,1:followme-check,2003,1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (ext-local,2003,1)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [2003@ext-local:1] Set("SIP/Cheapconnect-00000f32", "__RINGTIMER=25") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [2003@ext-local:2] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(__CWIGNORE=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [2003@ext-local:3] Macro("SIP/Cheapconnect-00000f32", "exten-vm,novm,2003,0,0,0") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/Cheapconnect-00000f32", "user-callerid,") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/Cheapconnect-00000f32", "TOUCH_MONITOR=1636558123.5639") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/Cheapconnect-00000f32", "CHANCONTEXT=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/Cheapconnect-00000f32", "CHANCONTEXT=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/Cheapconnect-00000f32", "CHANEXTENCONTEXT=Cheapconnect-00000f32") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/Cheapconnect-00000f32", "CHANEXTEN=Cheapconnect-00000f32") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:6] Set("SIP/Cheapconnect-00000f32", "CALLERID(number)=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/Cheapconnect-00000f32", "AMPUSER=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:8] Set("SIP/Cheapconnect-00000f32", "HOTDESCKCHAN=Cheapconnect-00000f32") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/Cheapconnect-00000f32", "HOTDESKEXTEN=Cheapconnect") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/Cheapconnect-00000f32", "HOTDESKCALL=0") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(HOTDESKCALL=1)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(CALLERID(name)=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/Cheapconnect-00000f32", "0?report") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/Cheapconnect-00000f32", "1?Set(REALCALLERIDNUM=XXXXXXXXXX)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/Cheapconnect-00000f32", "AMPUSER=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/Cheapconnect-00000f32", "0?limit") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/Cheapconnect-00000f32", "AMPUSERCIDNAME=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/Cheapconnect-00000f32", "1?report") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/Cheapconnect-00000f32", "Macro Depth is 2") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/Cheapconnect-00000f32", "1?report2:macroerror") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/Cheapconnect-00000f32", "0?continue") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/Cheapconnect-00000f32", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/Cheapconnect-00000f32", "__TTL=64") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/Cheapconnect-00000f32", "1?continue") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/Cheapconnect-00000f32", "CALLERID(number)=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/Cheapconnect-00000f32", "CALLERID(name)=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/Cheapconnect-00000f32", "0?cnum") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/Cheapconnect-00000f32", "CDR(cnam)=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/Cheapconnect-00000f32", "CDR(cnum)=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/Cheapconnect-00000f32", "CHANNEL(language)=nl") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/Cheapconnect-00000f32", "RingGroupMethod=none") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/Cheapconnect-00000f32", "__EXTTOCALL=2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/Cheapconnect-00000f32", "__PICKUPMARK=2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/Cheapconnect-00000f32", "RT=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("SIP/Cheapconnect-00000f32", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("SIP/Cheapconnect-00000f32", "0?Gosub(ext-intercom,*802003,1())") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("SIP/Cheapconnect-00000f32", "0?ChanSpy(SIP/2003,q)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("SIP/Cheapconnect-00000f32", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("SIP/Cheapconnect-00000f32", "0?Gosub(ext-intercom,*802003,1())") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("SIP/Cheapconnect-00000f32", "0?ChanSpy(SIP/2003,q)") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:18] ExecIf("SIP/Cheapconnect-00000f32", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:19] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:20] ExecIf("SIP/Cheapconnect-00000f32", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:21] ExecIf("SIP/Cheapconnect-00000f32", "0?MacroExit()") in new stack
[2021-11-10 16:28:45] ERROR[4562][C-00001130] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:22] Gosub("SIP/Cheapconnect-00000f32", "sub-record-check,s,1(exten,2003,dontcare)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/Cheapconnect-00000f32", "10?initialized") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,s,10)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/Cheapconnect-00000f32", "Recordings initialized") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(ARG3=dontcare)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:12] Set("SIP/Cheapconnect-00000f32", "REC_POLICY_MODE_SAVE=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(REC_STATUS=NO)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/Cheapconnect-00000f32", "5?checkaction") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,s,17)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/Cheapconnect-00000f32", "1?sub-record-check,exten,1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,exten,1)

This is part two:

[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/Cheapconnect-00000f32", "Exten Recording Check between XXXXXXXXXX and 2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/Cheapconnect-00000f32", "CALLTYPE=external") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(CALLTYPE=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/Cheapconnect-00000f32", "CALLEE=dontcare") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(CALLEE=dontcare)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/Cheapconnect-00000f32", "1?callee") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/Cheapconnect-00000f32", "recordcheck,1(dontcare,external,2003)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/Cheapconnect-00000f32", "Starting recording check against dontcare") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/Cheapconnect-00000f32", "dontcare") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:23] GotoIf("SIP/Cheapconnect-00000f32", "1?macrodial") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-exten-vm,s,29)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:29] GosubIf("SIP/Cheapconnect-00000f32", "0?clrheader,1()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-exten-vm:30] Macro("SIP/Cheapconnect-00000f32", "dial-one,,HhTtr,2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/Cheapconnect-00000f32", "DEXTEN=2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/Cheapconnect-00000f32", "__CRM_SOURCE=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:3] Gosub("SIP/Cheapconnect-00000f32", "zulu-user-settings-map,s,1(2003)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@zulu-user-settings-map:1] GotoIf("SIP/Cheapconnect-00000f32", "0?2003,1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@zulu-user-settings-map:2] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:4] Gosub("SIP/Cheapconnect-00000f32", "zulu-send-event,s,1(2003,XXXXXXXXXX)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@zulu-send-event:1] GotoIf("SIP/Cheapconnect-00000f32", "1?skippopup") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (zulu-send-event,s,4)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@zulu-send-event:4] NoOp("SIP/Cheapconnect-00000f32", "No Call Pop") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@zulu-send-event:5] UserEvent("SIP/Cheapconnect-00000f32", "zulu-call,eventtype:calling,extension:2003,type:,url:,cnam:MDY0NjAxMTgyOA==,cnum:XXXXXXXXXX,lid:1636558123.5639,from:XXXXXXXXXX,to:2003}") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@zulu-send-event:6] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:5] UserEvent("SIP/Cheapconnect-00000f32", "zulu-inbound-call,type:macro-dial-one,from:XXXXXXXXXX,to:2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:6] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(__EXTTOCALL=2003)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:7] Set("SIP/Cheapconnect-00000f32", "DIALSTATUS_CW=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:8] GosubIf("SIP/Cheapconnect-00000f32", "0?screen,1()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:9] GosubIf("SIP/Cheapconnect-00000f32", "0?cf,1()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/Cheapconnect-00000f32", "1?skip1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-dial-one,s,13)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:13] GotoIf("SIP/Cheapconnect-00000f32", "0?nodial") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:14] GotoIf("SIP/Cheapconnect-00000f32", "0?continue") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:15] Set("SIP/Cheapconnect-00000f32", "EXTHASCW=ENABLED") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:16] GotoIf("SIP/Cheapconnect-00000f32", "0?next1:cwinusebusy") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-dial-one,s,28)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/Cheapconnect-00000f32", "0?next3:continue") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-dial-one,s,30)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:30] GotoIf("SIP/Cheapconnect-00000f32", "0?nodial") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:31] GosubIf("SIP/Cheapconnect-00000f32", "1?dstring,1():dlocal,1()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/Cheapconnect-00000f32", "DSTRING=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/Cheapconnect-00000f32", "DEVICES=2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/Cheapconnect-00000f32", "0?Return()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(DEVICES=003)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/Cheapconnect-00000f32", "LOOPCNT=1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/Cheapconnect-00000f32", "ITER=1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/Cheapconnect-00000f32", "THISDIAL=SIP/2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("SIP/Cheapconnect-00000f32", "1?docheck") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-dial-one,dstring,14)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("SIP/Cheapconnect-00000f32", "0?skipset") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:15] Set("SIP/Cheapconnect-00000f32", "DSTRING=SIP/2003&") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/Cheapconnect-00000f32", "ITER=2") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("SIP/Cheapconnect-00000f32", "0?begin") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("SIP/Cheapconnect-00000f32", "0?Return()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:19] Set("SIP/Cheapconnect-00000f32", "DSTRING=SIP/2003") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [dstring@macro-dial-one:20] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:32] GotoIf("SIP/Cheapconnect-00000f32", "0?nodial") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:33] GotoIf("SIP/Cheapconnect-00000f32", "0?skiptrace") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:34] GosubIf("SIP/Cheapconnect-00000f32", "1?ctset,1():ctclear,1()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/Cheapconnect-00000f32", "DB(CALLTRACE/2003)=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:35] Set("SIP/Cheapconnect-00000f32", "D_OPTIONS=HhTtr") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:36] GosubIf("SIP/Cheapconnect-00000f32", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:37] NoOp("SIP/Cheapconnect-00000f32", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(ALERT_INFO=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(ALERT_INFO=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:40] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(ALERT_INFO=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:41] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:42] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:43] GosubIf("SIP/Cheapconnect-00000f32", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:44] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(CHANNEL(musicclass)=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:45] GosubIf("SIP/Cheapconnect-00000f32", "0?qwait,1()") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:46] Set("SIP/Cheapconnect-00000f32", "__CWIGNORE=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:47] Set("SIP/Cheapconnect-00000f32", "__KEEPCID=TRUE") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:48] GotoIf("SIP/Cheapconnect-00000f32", "0?usegoto,1") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:49] GotoIf("SIP/Cheapconnect-00000f32", "1?godial") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-dial-one,s,54)
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:54] Macro("SIP/Cheapconnect-00000f32", "dialout-one-predial-hook,") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:55] ExecIf("SIP/Cheapconnect-00000f32", "1?Set(D_OPTIONS=HhtrI)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:56] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:57] Set("SIP/Cheapconnect-00000f32", "__ZULU_TIMEOUT=25") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:58] Set("SIP/Cheapconnect-00000f32", "__ZULU_FROMEXTEN=XXXXXXXXXX") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:59] Set("SIP/Cheapconnect-00000f32", "__ZULU_DIALOPTS=HhTtr") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:60] NoOp("SIP/Cheapconnect-00000f32", "Finished") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:61] NoOp("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:62] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(D_OPTIONS=HhtrIg)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-dial-one:63] Dial("SIP/Cheapconnect-00000f32", "SIP/2003,,HhtrIb(func-apply-sipheaders^s^1)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] netsock2.c: Using SIP VIDEO TOS bits 136
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] netsock2.c: Using SIP VIDEO CoS mark 6
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] netsock2.c: Using SIP RTP TOS bits 184
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] netsock2.c: Using SIP RTP CoS mark 5
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] app_stack.c: SIP/2003-00000f33 Internal Gosub(func-apply-sipheaders,s,1) start
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/2003-00000f33", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/2003-00000f33", "Applying SIP Headers to channel SIP/2003-00000f33") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/2003-00000f33", "TECH=SIP") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/2003-00000f33", "SIPHEADERKEYS=") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/2003-00000f33", "0") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] app_while.c: Jumping to priority 13
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/2003-00000f33", "") in new stack
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] app_stack.c: Spawn extension (from-internal, 2003, 1) exited non-zero on 'SIP/2003-00000f33'
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] app_stack.c: SIP/2003-00000f33 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] app_dial.c: Called SIP/2003
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] app_dial.c: Connected line update to SIP/Cheapconnect-00000f32 prevented.
[2021-11-10 16:28:45] VERBOSE[2404] chan_sip.c: Extension Changed 2003[ext-local] new state Ringing for Notify User 2000
[2021-11-10 16:28:45] VERBOSE[2404] chan_sip.c: Extension Changed 2003[ext-local] new state Ringing for Notify User 2011
[2021-11-10 16:28:45] VERBOSE[4562][C-00001130] app_dial.c: SIP/2003-00000f33 is ringing
[2021-11-10 16:28:45] VERBOSE[2404] chan_sip.c: Extension Changed 2003[ext-local] new state Ringing for Notify User 2000
[2021-11-10 16:28:45] VERBOSE[2404] chan_sip.c: Extension Changed 2003[ext-local] new state Ringing for Notify User 2011
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] app_stack.c: SIP/2003-00000f33 Internal Gosub(crm-hangup,s,1) start
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/2003-00000f33", "Sending Hangup to CRM") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/2003-00000f33", "HANGUP CAUSE: 16") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/2003-00000f33", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/2003-00000f33", "MASTER CHANNEL: 1636558125.5640 = 1636558123.5639") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/2003-00000f33", "1?return") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (crm-hangup,s,8)
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:8] Return("SIP/2003-00000f33", "") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] app_stack.c: Spawn extension (from-internal, 2003, 1) exited non-zero on 'SIP/2003-00000f33'
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] app_stack.c: SIP/2003-00000f33 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] app_macro.c: Spawn extension (macro-dial-one, s, 63) exited non-zero on 'SIP/Cheapconnect-00000f32' in macro 'dial-one'
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] app_macro.c: Spawn extension (macro-exten-vm, s, 30) exited non-zero on 'SIP/Cheapconnect-00000f32' in macro 'exten-vm'
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Spawn extension (ext-local, 2003, 3) exited non-zero on 'SIP/Cheapconnect-00000f32'
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [h@ext-local:1] Macro("SIP/Cheapconnect-00000f32", "hangupcall,") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/Cheapconnect-00000f32", "1?theend") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2021-11-10 16:28:53] VERBOSE[2404] chan_sip.c: Extension Changed 2003[ext-local] new state Idle for Notify User 2000
[2021-11-10 16:28:53] VERBOSE[2404] chan_sip.c: Extension Changed 2003[ext-local] new state Idle for Notify User 2011
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(CDR(recordingfile)=)") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/Cheapconnect-00000f32", "SIP/2003-00000f33 montior file= ") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/Cheapconnect-00000f32", "1?skipagi") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/Cheapconnect-00000f32' in macro 'hangupcall'
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/Cheapconnect-00000f32'
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] app_stack.c: SIP/Cheapconnect-00000f32 Internal Gosub(crm-hangup,s,1) start
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/Cheapconnect-00000f32", "Sending Hangup to CRM") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/Cheapconnect-00000f32", "HANGUP CAUSE: 16") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/Cheapconnect-00000f32", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/Cheapconnect-00000f32", "MASTER CHANNEL: 1636558123.5639 = 1636558123.5639") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/Cheapconnect-00000f32", "0?return") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:6] Set("SIP/Cheapconnect-00000f32", "__CRM_HANGUP=1") in new stack
[2021-11-10 16:28:53] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/Cheapconnect-00000f32", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2021-11-10 16:28:54] VERBOSE[4562][C-00001130] res_agi.c: <SIP/Cheapconnect-00000f32>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2021-11-10 16:28:54] VERBOSE[4562][C-00001130] pbx.c: Executing [s@crm-hangup:8] Return("SIP/Cheapconnect-00000f32", "") in new stack
[2021-11-10 16:28:54] VERBOSE[4562][C-00001130] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/Cheapconnect-00000f32'
[2021-11-10 16:28:54] VERBOSE[4562][C-00001130] app_stack.c: SIP/Cheapconnect-00000f32 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

As Lorne mentioned, post a pastebin link. It’s all documented in that Wiki.

I’m sorry,
I misunderstood that part. I hope I did it right now:

https://pastebin.freepbx.org/view/0e37afe0

lines 69 -72 show that it didn’t work for whatever reason , enable agi debugging (agi set debug on) and repost

When I do that, it says agi: command not found

You do that within the asterisk cli, If you are watching the log from a shell you can effectively do the same with

rasterisk -x 'agi set debug on'

https://pastebin.freepbx.org/view/e08962c1
How about now?

It helps the diagnostics but line 365 says that the script is not working as intended, I would take this to the author.

How can I check if the engine is installed?

From

https://git.freepbx.org/projects/FREEPBX/repos/tts/browse/agi-bin/propolys-tts.agi

The engine used is defined in lines following 99, in your case it seems to be flite

Maybe it’s a good idea to install another engine first, and see if that one works or if it has the same problem. I’m not sure how to do that, going to google for that but when you know a link to an easy howto, feel free to post :wink:
And thank you, I really appreciate your help!

Of all those engines, only the Amazon ‘polly’ engine would klinkt goed

edit:

does flite work for an English speaker?

I found Polly over here:

Are you Dutch dicko?

I’m strugling with the instructions there. I’m not so good with this. I have Putty but some of the commands mentioned there are not working. for example

git clone https://github.com/awslabs/aws-nodejs-sample

Probably because I do something wrong.

Is git installed on your system?

To be honest, no idea…

This might be ‘Een lange rij om te schoffelen’, are you sure you want to invest that time ?

:rofl: I’m ready to “schoffel” lol!