FreePBX 15 not save/create Voicemails


(Stefan Baston) #1

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


(Matthew Jensen) #2

I can’t comment on any potential upgrade/restore problems, but it looks like the system may think the messages are silent and thus not saving them. I’m not sure if it always shows this message though:

maxsilence should be less than minsecs or you may get empty messages

Is there audio in the voicemails you are trying to leave? Perhaps the external audio is not making it’s way into your server. I presume you have 2 way audio on external calls?


(Stefan Baston) #3

Hi Mathew,

thank You for your reply.
The macsilence setting is the default setting and its the same setting as on a PBX what we using at a other site building. And there its working without problems.

As an update i rebuild now a PBX 6.12.65-30 as it was befor our crash and restored the same backup but external call still not saved. Internal voicmails working great and i can listen them too.

Additonal i have sometimes the problem that extensions are unavalable after one ring or sometimes after four rings. The ringtime default is set to 20 seconds.

Thanks in advanced