Hi there,
i have a problem since i upgraded to the newest Version of FreePBX.
Background:
Two weeks ago we had a problem with our running PBX server 6.12.65.
We installed on a similar server the newest Version:
PBX Version: 15.0.16.68
PBX Distro: 12.7.6-2002-2.sng7
Asterisk Version: 16.6.2.
We used the last backup from our old PBX and restore it on the new server and it worked good so far.
Today we imagine a problem with our Voicemails which will not saved/created.
When i transferred manually a old voicemail to a extension ( is linked to a queues which is our main number ) related folder it will recognize without problems in the PBX web interface and in the UCP.
When i call internal to a extension and left a message the message will created and shown up without problems.
When i call now from external and left, than a message all before saved messages are deleted.
I’m not sure if something went wrong at the restore of the backup or its a bug?
I already found some threads where other users had problems too after a upgrade with some modules and after another restore the problems were fixed, could this be a option?
For additional information i attach a part of my full logfile from a related call where i can see that the file will created in the tmp directory of the extension “1010” but its not there after hanging up.
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:1] Set(“SIP/Easybell-0000003b”, “__DIRECTION=INBOUND”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:2] Gosub(“SIP/Easybell-0000003b”, “sub-record-check,s,1(in,our main Phone number,dontcare)”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/Easybell-0000003b”, “0?initialized”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/Easybell-0000003b”, “__REC_STATUS=INITIALIZED”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/Easybell-0000003b”, “NOW=1596104873”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/Easybell-0000003b”, “__DAY=30”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/Easybell-0000003b”, “__MONTH=07”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/Easybell-0000003b”, “__YEAR=2020”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/Easybell-0000003b”, “__TIMESTR=20200730-122753”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/Easybell-0000003b”, “__FROMEXTEN=unknown”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/Easybell-0000003b”, “__MON_FMT=wav”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/Easybell-0000003b”, “Recordings initialized”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/Easybell-0000003b”, “0?Set(ARG3=dontcare)”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/Easybell-0000003b”, “REC_POLICY_MODE_SAVE=”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/Easybell-0000003b”, “0?Set(REC_STATUS=NO)”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/Easybell-0000003b”, “2?checkaction”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (sub-record-check,s,17)
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/Easybell-0000003b”, “1?sub-record-check,in,1”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (sub-record-check,in,1)
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [in@sub-record-check:1] NoOp(“SIP/Easybell-0000003b”, “Inbound Recording Check to our main Phone number”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [in@sub-record-check:2] Set(“SIP/Easybell-0000003b”, “FROMEXTEN=unknown”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [in@sub-record-check:3] ExecIf(“SIP/Easybell-0000003b”, “11?Set(FROMEXTEN=my Phone number)”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [in@sub-record-check:4] Gosub(“SIP/Easybell-0000003b”, “recordcheck,1(dontcare,in,our main Phone number)”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/Easybell-0000003b”, “Starting recording check against dontcare”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/Easybell-0000003b”, “dontcare”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [in@sub-record-check:5] Return(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:3] Set(“SIP/Easybell-0000003b”, “CHANNEL(tonezone)=de”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:4] Set(“SIP/Easybell-0000003b”, “__FROM_DID=our main Phone number”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:5] Set(“SIP/Easybell-0000003b”, “returnhere=1”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:6] Gosub(“SIP/Easybell-0000003b”, “app-blacklist-check,s,1()”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“SIP/Easybell-0000003b”, “0?blacklisted”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@app-blacklist-check:2] Set(“SIP/Easybell-0000003b”, “CALLED_BLACKLIST=1”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [s@app-blacklist-check:3] Return(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:7] Set(“SIP/Easybell-0000003b”, “CDR(did)=our main Phone number”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:8] GotoIf(“SIP/Easybell-0000003b”, “0?”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:9] ExecIf(“SIP/Easybell-0000003b”, “1 ?Set(CALLERID(name)=my Phone number)”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:10] Set(“SIP/Easybell-0000003b”, “__MOHCLASS=”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:11] Set(“SIP/Easybell-0000003b”, “__REVERSAL_REJECT=FALSE”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:12] GotoIf(“SIP/Easybell-0000003b”, “1?post-reverse-charge”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (from-trunk,our main Phone number,14)
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:14] NoOp(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:15] Set(“SIP/Easybell-0000003b”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:16] Set(“SIP/Easybell-0000003b”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:17] Set(“SIP/Easybell-0000003b”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:18] Set(“SIP/Easybell-0000003b”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:19] Set(“SIP/Easybell-0000003b”, “CIDSFSCHEME=YmFzZV9EZWZhdWx0”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:20] AGI(“SIP/Easybell-0000003b”, “agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Executing Scheme…
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Scheme Asked is: Default
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The DID is: our main Phone number
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNUM is: my Phone number
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNAME is: my Phone number
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi:
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Starting scheme Default
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Converting result to UTF-8
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting caller id to: Stefan Baston Mobile
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting channel variable SUPERFECTA_NEW: Stefan Baston Mobile
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting channel variable SUPERFECTA_OLD: my Phone number
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Determined to be: ‘Stefan Baston Mobile’
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Attempting to set lookupcid
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] res_agi.c: <SIP/Easybell-0000003b>AGI Script agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:21] Set(“SIP/Easybell-0000003b”, “CALLERID(name)=Stefan Baston Mobile”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:22] NoOp(“SIP/Easybell-0000003b”, “CallerID Entry Point”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:23] Set(“SIP/Easybell-0000003b”, “__CRM_DIRECTION=INBOUND”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:24] Set(“SIP/Easybell-0000003b”, “__CRM_SOURCE=my Phone number”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:25] Set(“SIP/Easybell-0000003b”, “__CRM_LINKEDID=1596104873.195”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:26] AGI(“SIP/Easybell-0000003b”, “agi://127.0.0.1/sangomacrm.agi,true”) in new stack
[2020-07-30 12:27:54] VERBOSE[18893][C-00000019] res_agi.c: <SIP/Easybell-0000003b>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2020-07-30 12:27:54] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone number@from-trunk:27] ExecIf(“SIP/Easybell-0000003b”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2020-07-30 12:28:37] VERBOSE[19524][C-00000019] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-07-30 12:28:37] VERBOSE[19524][C-00000019] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“Local/1011@from-queue-00000041;2”, “”) in new stack
[2020-07-30 12:28:37] VERBOSE[19524][C-00000019] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘Local/1011@from-queue-00000041;2’ in macro ‘hangupcall’
[2020-07-30 12:28:37] VERBOSE[19524][C-00000019] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘Local/1011@from-queue-00000041;2’
[2020-07-30 12:28:37] VERBOSE[19542][C-00000019] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“Local/FMPR-1014@from-internal-00000043;2”, "SIP/1014-00000044 montior file= ") in new stack
[2020-07-30 12:28:37] VERBOSE[10797] chan_sip.c: Extension Changed 1014[ext-local] new state Idle for Notify User 1022
[2020-07-30 12:28:37] VERBOSE[10797] chan_sip.c: Extension Changed 1014[ext-local] new state Idle for Notify User 1024
[2020-07-30 12:28:37] VERBOSE[10797] chan_sip.c: Extension Changed 1014[ext-local] new state Idle for Notify User 1031
[2020-07-30 12:28:37] VERBOSE[19542][C-00000019] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“Local/FMPR-1014@from-internal-00000043;2”, “1?skipagi”) in new stack
[2020-07-30 12:28:37] VERBOSE[19542][C-00000019] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-07-30 12:28:37] VERBOSE[19542][C-00000019] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“Local/FMPR-1014@from-internal-00000043;2”, “”) in new stack
[2020-07-30 12:28:37] VERBOSE[10797] chan_sip.c: Extension Changed 1014[ext-local] new state Idle for Notify User 1012
[2020-07-30 12:29:07] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:07] VERBOSE[19975] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:07] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:07] VERBOSE[19977] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:07] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:07] VERBOSE[19979] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:07] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:07] VERBOSE[19997] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:07] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:07] VERBOSE[19999] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:07] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:07] VERBOSE[20001] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] app.c: User hung up
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] app_macro.c: Spawn extension (macro-vm, s-INSTRUCT, 3) exited non-zero on ‘SIP/Easybell-0000003b’ in macro ‘vm’
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Spawn extension (ext-local, vmi1010, 1) exited non-zero on ‘SIP/Easybell-0000003b’
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [h@ext-local:1] Macro(“SIP/Easybell-0000003b”, “hangupcall,”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/Easybell-0000003b”, “1?theend”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/Easybell-0000003b”, “0?Set(CDR(recordingfile)=)”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/Easybell-0000003b”, " montior file= ") in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“SIP/Easybell-0000003b”, “1?skipagi”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/Easybell-0000003b’ in macro ‘hangupcall’
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/Easybell-0000003b’
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] app_stack.c: SIP/Easybell-0000003b Internal Gosub(crm-hangup,s,1) start
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/Easybell-0000003b”, “Sending Hangup to CRM”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/Easybell-0000003b”, “HANGUP CAUSE: 16”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/Easybell-0000003b”, “1?Set(__CRM_VOICEMAIL=SUCCESS)”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/Easybell-0000003b”, “MASTER CHANNEL: 1596104873.195 = 1596104873.195”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/Easybell-0000003b”, “0?return”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/Easybell-0000003b”, “__CRM_HANGUP=1”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/Easybell-0000003b”, “agi://127.0.0.1/sangomacrm.agi”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] res_agi.c: <SIP/Easybell-0000003b>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/Easybell-0000003b’
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] app_stack.c: SIP/Easybell-0000003b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [vmx@macro-vm:13] GotoIf(“SIP/Easybell-0000003b”, “1?notdirect”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (macro-vm,vmx,15)
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [vmx@macro-vm:15] NoOp(“SIP/Easybell-0000003b”, "Checking if ext 1010 is enabled: ") in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [vmx@macro-vm:16] GotoIf(“SIP/Easybell-0000003b”, “1?s-INSTRUCT,1”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (macro-vm,s-INSTRUCT,1)
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [s-INSTRUCT@macro-vm:1] NoOp(“SIP/Easybell-0000003b”, “NOMESSAGE (beeb only) voicemail”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [s-INSTRUCT@macro-vm:2] Macro(“SIP/Easybell-0000003b”, “get-vmcontext,1010”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/Easybell-0000003b”, “VMCONTEXT=default”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/Easybell-0000003b”, “0?200:300”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [s-INSTRUCT@macro-vm:3] VoiceMail(“SIP/Easybell-0000003b”, “1010@default,”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] file.c: <SIP/Easybell-0000003b> Playing ‘vm-intro.ulaw’ (language ‘en_GB’)
[2020-07-30 12:29:01] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:01] VERBOSE[19925] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:01] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:01] VERBOSE[19929] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:01] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:29:01] VERBOSE[19931] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:29:02] VERBOSE[18893][C-00000019] file.c: <SIP/Easybell-0000003b> Playing ‘beep.ulaw’ (language ‘en_GB’)
[2020-07-30 12:29:02] VERBOSE[18893][C-00000019] app_voicemail.c: Recording the message
[2020-07-30 12:29:02] VERBOSE[18893][C-00000019] app.c: x=0, open writing: /var/spool/asterisk/voicemail/default/1010/tmp/0UL4UO format: wav, 0x7f9278021b90 => here it tries to create the voicemail
[2020-07-30 12:29:51] NOTICE[10952] chan_sip.c: Received SIP subscribe for peer without mailbox: 1097
[2020-07-30 12:29:52] NOTICE[10952] chan_sip.c: Received SIP subscribe for peer without mailbox: 1097
[2020-07-30 12:30:02] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:30:02] VERBOSE[21240] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:30:02] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:30:02] VERBOSE[21242] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:30:02] VERBOSE[10784] asterisk.c: Remote UNIX connection
[2020-07-30 12:30:02] VERBOSE[21244] asterisk.c: Remote UNIX connection disconnected
[2020-07-30 12:30:13] VERBOSE[21428] loader.c: Reloading module ‘app_voicemail.so’ (Comedian Mail (Voicemail System))
[2020-07-30 12:30:13] WARNING[21428] app_voicemail.c: maxsilence should be less than minsecs or you may get empty messages
[2020-07-30 12:30:22] NOTICE[10952] chan_sip.c: Received SIP subscribe for peer without mailbox: 1047
I hope someone can help me.
Thank You
Kindest regards