FreePBX 15 not save/create Voicemails

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 [email protected]: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 [email protected]: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 [[email protected]:1] GotoIf(“SIP/Easybell-0000003b”, “0?initialized”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:2] Set(“SIP/Easybell-0000003b”, “__REC_STATUS=INITIALIZED”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:3] Set(“SIP/Easybell-0000003b”, “NOW=1596104873”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:4] Set(“SIP/Easybell-0000003b”, “__DAY=30”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:5] Set(“SIP/Easybell-0000003b”, “__MONTH=07”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:6] Set(“SIP/Easybell-0000003b”, “__YEAR=2020”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:7] Set(“SIP/Easybell-0000003b”, “__TIMESTR=20200730-122753”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:8] Set(“SIP/Easybell-0000003b”, “__FROMEXTEN=unknown”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:9] Set(“SIP/Easybell-0000003b”, “__MON_FMT=wav”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:10] NoOp(“SIP/Easybell-0000003b”, “Recordings initialized”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/Easybell-0000003b”, “FROMEXTEN=unknown”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:5] Return(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [[email protected]:1] GotoIf(“SIP/Easybell-0000003b”, “0?blacklisted”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:2] Set(“SIP/Easybell-0000003b”, “CALLED_BLACKLIST=1”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:3] Return(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]:14] NoOp(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:27:53] VERBOSE[18893][C-00000019] pbx.c: Executing [our main Phone [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [[email protected]:7] Hangup(“Local/[email protected];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/[email protected];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/[email protected];2’
[2020-07-30 12:28:37] VERBOSE[19542][C-00000019] pbx.c: Executing [[email protected]:4] NoOp(“Local/[email protected];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 [[email protected]:5] GotoIf(“Local/[email protected];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 [[email protected]:7] Hangup(“Local/[email protected];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 [[email protected]:1] Macro(“SIP/Easybell-0000003b”, “hangupcall,”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:4] NoOp(“SIP/Easybell-0000003b”, " montior file= ") in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] NoOp(“SIP/Easybell-0000003b”, “HANGUP CAUSE: 16”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:5] GotoIf(“SIP/Easybell-0000003b”, “0?return”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:6] Set(“SIP/Easybell-0000003b”, “__CRM_HANGUP=1”) in new stack
[2020-07-30 12:29:09] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Macro(“SIP/Easybell-0000003b”, “get-vmcontext,1010”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:1] Set(“SIP/Easybell-0000003b”, “VMCONTEXT=default”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]: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 [[email protected]:300] NoOp(“SIP/Easybell-0000003b”, “”) in new stack
[2020-07-30 12:28:56] VERBOSE[18893][C-00000019] pbx.c: Executing [[email protected]:3] VoiceMail(“SIP/Easybell-0000003b”, “[email protected],”) 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

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?

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

This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.