Voicemail mailbox hangs up when there is only one voicemail

[a0429 (United Kingdom)]

Can somebody help me identify the problem here?

When I have voicemail enabled on an extension i can dial into the mailbox fine.

As soon as there is one voicemail in the extension, I get the message “you have” then it hangs up (log below)

If I have two voicemails then it continues and plays my voicemail.

[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:1] Macro(“PJSIP/101010-0003b071”, “user-callerid,”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/101010-0003b071”, “TOUCH_MONITOR=1580230875.360529”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/101010-0003b071”, “AMPUSER=101010”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/101010-0003b071”, “0?report”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/101010-0003b071”, “1?Set(REALCALLERIDNUM=101010)”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/101010-0003b071”, “AMPUSER=101010”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/101010-0003b071”, “0?limit”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/101010-0003b071”, “AMPUSERCIDNAME=AK TEST”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/101010-0003b071”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/101010-0003b071”, “0?report”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/101010-0003b071”, “AMPUSERCID=101010”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/101010-0003b071”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/101010-0003b071”, “CALLERID(all)=“AK TEST” <101010>”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“PJSIP/101010-0003b071”, “0?Set(CALLERID(all)=)”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“PJSIP/101010-0003b071”, “0?limit”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/101010-0003b071”, “0?Set(GROUP(concurrency_limit)=101010)”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:16] ExecIf(“PJSIP/101010-0003b071”, “0?Set(CHANNEL(language)=)”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:17] NoOp(“PJSIP/101010-0003b071”, “Macro Depth is 1”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/101010-0003b071”, “1?report2:macroerror”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/101010-0003b071”, “0?continue”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:20] ExecIf(“PJSIP/101010-0003b071”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:21] Set(“PJSIP/101010-0003b071”, “__TTL=64”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:22] GotoIf(“PJSIP/101010-0003b071”, “1?continue”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/101010-0003b071”, “CALLERID(number)=101010”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:39] Set(“PJSIP/101010-0003b071”, “CALLERID(name)=AK TEST”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:40] GotoIf(“PJSIP/101010-0003b071”, “0?cnum”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/101010-0003b071”, “CDR(cnam)=AK TEST”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/101010-0003b071”, “CDR(cnum)=101010”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-user-callerid:43] Set(“PJSIP/101010-0003b071”, “CHANNEL(language)=de_DE”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:2] Set(“PJSIP/101010-0003b071”, “CONNECTEDLINE(name-charset,i)=utf8”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:3] Set(“PJSIP/101010-0003b071”, “CONNECTEDLINE(name,i)=My Voicemail”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:4] Set(“PJSIP/101010-0003b071”, “CONNECTEDLINE(num,i)=101010”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:5] Answer(“PJSIP/101010-0003b071”, “”) in new stack
[2020-01-28 18:01:15] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:6] Wait(“PJSIP/101010-0003b071”, “1”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:7] Macro(“PJSIP/101010-0003b071”, “get-vmcontext,101010”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“PJSIP/101010-0003b071”, “VMCONTEXT=default”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/101010-0003b071”, “0?200:300”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/101010-0003b071”, “”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:8] Set(“PJSIP/101010-0003b071”, “VMBOXEXISTSSTATUS=SUCCESS”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:9] GotoIf(“PJSIP/101010-0003b071”, “1?mbexist”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:109] GotoIf(“PJSIP/101010-0003b071”, “0?novmpw:vmpw”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [*97@from-internal:118] VoiceMailMain(“PJSIP/101010-0003b071”, “101010@default”) in new stack
[2020-01-28 18:01:16] VERBOSE[13563][C-0001ff8b] file.c: <PJSIP/101010-0003b071> Playing ‘vm-password.ulaw’ (language ‘de_DE’)
[2020-01-28 18:01:26] VERBOSE[13563][C-0001ff8b] file.c: <PJSIP/101010-0003b071> Playing ‘vm-youhave.ulaw’ (language ‘de_DE’)
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Spawn extension (from-internal, *97, 118) exited non-zero on ‘PJSIP/101010-0003b071’
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/101010-0003b071”, “hangupcall”) in new stack
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/101010-0003b071”, “1?theend”) in new stack
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/101010-0003b071”, “0?Set(CDR(recordingfile)=)”) in new stack
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/101010-0003b071”, " montior file= ") in new stack
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“PJSIP/101010-0003b071”, “1?skipagi”) in new stack
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“PJSIP/101010-0003b071”, “”) in new stack
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘PJSIP/101010-0003b071’ in macro ‘hangupcall’
[2020-01-28 18:01:27] VERBOSE[13563][C-0001ff8b] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/101010-0003b071’

[billsimon]

It is looking among the German (de_DE) language set for the files. Is that what you intend? If so, check in the sound files location /var/lib/asterisk/sounds for the sound files corresponding to the numbers, under the “de_DE” folder.

if not, go to Admin - Sound Languages, download the language you prefer and set it as the system default in the Settings menu.

[lgaetz (Lorne Gaetz)]

@a0429

CHANNEL(language)=de_DE

Seen this before, a sound file is missing for German and Dutch. You can see a work around in this ticket:
https://issues.freepbx.org/browse/FREEPBX-17153

Dutch Voicemail problems - Solved!

From the linked ticket: There is an easy fix, copy the file after which both vm-INBOX.ulaw and vm-INBOXs.ulaw exist. Get a list of all the relevant files: ll /var/lib/asterisk/sounds/nl/vm-INBOX* make copies with the command: cp /var/lib/asterisk/sounds/nl/vm-INBOX.ulaw /var/lib/asterisk/sounds/nl/vm-INBOXs.ulaw when all done fix file ownership/perms with: fwconsole chown

[a0429 (United Kingdom)]

Yes, I have set the DE language files, and downloaded from here. It seems 1 digit seems to be missing, however I have checked it is there.

[a0429 (United Kingdom)]

@a0429

[2020-01-28 18:01:26] VERBOSE[13563][C-0001ff8b] file.c: <PJSIP/101010-0003b071> Playing ‘vm-youhave.ulaw’ (language ‘de_DE’)

Seems when the language is en, it works. It does not work when set to nl or de

EN
[2020-01-29 12:42:49] VERBOSE[10297][C-00021224] pbx.c: Executing [*97@from-internal:109] GotoIf(“PJSIP/101010-0003d1e7”, “0?novmpw:vmpw”) in new stack
[2020-01-29 12:42:49] VERBOSE[10297][C-00021224] pbx.c: Executing [*97@from-internal:118] VoiceMailMain(“PJSIP/101010-0003d1e7”, “101010@default”) in new stack
[2020-01-29 12:42:49] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-password.ulaw’ (language ‘en’)
[2020-01-29 12:42:54] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-youhave.ulaw’ (language ‘en’)
[2020-01-29 12:42:54] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘digits/1.ulaw’ (language ‘en’)
[2020-01-29 12:42:55] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-INBOX.ulaw’ (language ‘en’)
[2020-01-29 12:42:56] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-message.ulaw’ (language ‘en’)
[2020-01-29 12:42:57] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-onefor.ulaw’ (language ‘en’)
[2020-01-29 12:42:59] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-INBOX.ulaw’ (language ‘en’)
[2020-01-29 12:42:59] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-messages.ulaw’ (language ‘en’)
[2020-01-29 12:43:01] VERBOSE[10297][C-00021224] file.c: <PJSIP/101010-0003d1e7> Playing ‘vm-opts.ulaw’ (language ‘en’)

DE

[2020-01-29 12:44:01] VERBOSE[13438][C-00021234] pbx.c: Executing [*97@from-internal:118] VoiceMailMain(“PJSIP/101010-0003d202”, “101010@default”) in new stack
[2020-01-29 12:44:01] VERBOSE[13438][C-00021234] file.c: <PJSIP/101010-0003d202> Playing ‘vm-password.ulaw’ (language ‘de_DE’)
[2020-01-29 12:44:08] VERBOSE[13438][C-00021234] file.c: <PJSIP/101010-0003d202> Playing ‘vm-youhave.ulaw’ (language ‘de_DE’)
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Spawn extension (from-internal, *97, 118) exited non-zero on ‘PJSIP/101010-0003d202’
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/101010-0003d202”, “hangupcall”) in new stack
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/101010-0003d202”, “1?theend”) in new stack
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/101010-0003d202”, “0?Set(CDR(recordingfile)=)”) in new stack
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/101010-0003d202”, " montior file= ") in new stack
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“PJSIP/101010-0003d202”, “1?skipagi”) in new stack
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“PJSIP/101010-0003d202”, “”) in new stack
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘PJSIP/101010-0003d202’ in macro ‘hangupcall’
[2020-01-29 12:44:10] VERBOSE[13438][C-00021234] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/101010-0003d202’

When searching for digits/1.ulaw it seems the files are there:
locate digits/1.ulaw
/var/lib/asterisk/sounds/de_DE/digits/1.ulaw
/var/lib/asterisk/sounds/en/digits/1.ulaw
/var/lib/asterisk/sounds/en_GB/digits/1.ulaw
/var/lib/asterisk/sounds/es/digits/1.ulaw
/var/lib/asterisk/sounds/fr/digits/1.ulaw
/var/lib/asterisk/sounds/it/digits/1.ulaw
/var/lib/asterisk/sounds/ja/digits/1.ulaw

The voice files for “you have” in DE are there as they seem to be played. It is when it gets to the “1 voicemail” part it fails, yet the file is there and it hangs up right after “you have” is heard.

Any ideas?

Thanks

[a0429 (United Kingdom)]

Found the error by not just using tail-f with a grep on the extension!

Thanks!

[2020-01-29 12:56:23] WARNING[22239][C-00021319]: file.c:774 ast_openstream_full: File digits/1F does not exist in any format