IVR, pressing # after leaving message, error announced

After leaving message and pressing # announcement says there is an error. Has anyone else encountered this? Can you test this to see if it is common to everyone or just me?

Thanks.

Wow, how long have you been at this?

Please post a question that can be answered.

1 - FreePBX version
2 - Asterisk Verions
3 - What type of message? VM or system recording?
4 - Log excerpt from time of error

So I guess the answer is no, you aren’t aware of this error.

Distro 3.211.63-7 The IVR message is something like “Please leave a message after the beep, when you are done you may hang up or press # for more options”. Pressing # plays an IVR message saying an error has occurred.
Am just arriving home and will do more testing.

[2013-05-28 17:59:38] VERBOSE[26069] file.c: -- Playing '/var/spool/asterisk/voicemail/default/301/unavail.slin' (language 'en') [2013-05-28 17:59:38] VERBOSE[26069] file.c: -- Playing '/var/spool/asterisk/voicemail/default/301/unavail.slin' (language 'en') [2013-05-28 17:59:44] VERBOSE[26069] file.c: -- Playing 'vm-intro.ulaw' (language 'en') [2013-05-28 17:59:44] VERBOSE[26069] file.c: -- Playing 'vm-intro.ulaw' (language 'en') [2013-05-28 17:59:49] VERBOSE[26069] file.c: -- Playing 'beep.ulaw' (language 'en') [2013-05-28 17:59:49] VERBOSE[26069] file.c: -- Playing 'beep.ulaw' (language 'en') [2013-05-28 17:59:50] VERBOSE[26069] app_voicemail.c: -- Recording the message [2013-05-28 17:59:50] VERBOSE[26069] app_voicemail.c: -- Recording the message [2013-05-28 17:59:50] VERBOSE[26069] app.c: -- x=0, open writing: /var/spool/asterisk/voicemail/default/301/tmp/0ZQ4XT format: wav, 0xb7331978 [2013-05-28 17:59:50] VERBOSE[26069] app.c: -- x=0, open writing: /var/spool/asterisk/voicemail/default/301/tmp/0ZQ4XT format: wav, 0xb7331978 [2013-05-28 17:59:52] VERBOSE[26069] app.c: -- User ended message by pressing # [2013-05-28 17:59:52] VERBOSE[26069] app.c: -- User ended message by pressing # [2013-05-28 17:59:52] VERBOSE[26069] file.c: -- Playing 'auth-thankyou.ulaw' (language 'en') [2013-05-28 17:59:52] VERBOSE[26069] file.c: -- Playing 'auth-thankyou.ulaw' (language 'en') [2013-05-28 17:59:53] VERBOSE[26069] app_voicemail.c: -- Recording was 0 seconds long but needs to be at least 12 - abandoning [2013-05-28 17:59:53] VERBOSE[26069] app_voicemail.c: -- Recording was 0 seconds long but needs to be at least 12 - abandoning [2013-05-28 17:59:53] VERBOSE[26069] pbx.c: -- Executing [s-NOANSWER@macro-vm:3] Goto("SIP/300-00000123", "exit-FAILED,1") in new stack [2013-05-28 17:59:53] VERBOSE[26069] pbx.c: -- Executing [s-NOANSWER@macro-vm:3] Goto("SIP/300-00000123", "exit-FAILED,1") in new stack [2013-05-28 17:59:53] VERBOSE[26069] pbx.c: -- Goto (macro-vm,exit-FAILED,1) [2013-05-28 17:59:53] VERBOSE[26069] pbx.c: -- Goto (macro-vm,exit-FAILED,1) [2013-05-28 17:59:53] VERBOSE[26069] pbx.c: -- Executing [exit-FAILED@macro-vm:1] Playback("SIP/300-00000123", "im-sorry&an-error-has-occured") in new stack [2013-05-28 17:59:53] VERBOSE[26069] pbx.c: -- Executing [exit-FAILED@macro-vm:1] Playback("SIP/300-00000123", "im-sorry&an-error-has-occured") in new stack [2013-05-28 17:59:53] VERBOSE[26069] file.c: -- Playing 'im-sorry.ulaw' (language 'en') [2013-05-28 17:59:53] VERBOSE[26069] file.c: -- Playing 'im-sorry.ulaw' (language 'en') [2013-05-28 17:59:54] VERBOSE[26069] file.c: -- Playing 'an-error-has-occured.ulaw' (language 'en') [2013-05-28 17:59:54] VERBOSE[26069] file.c: -- Playing 'an-error-has-occured.ulaw' (language 'en') [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [exit-FAILED@macro-vm:2] GotoIf("SIP/300-00000123", "0?exit-RETURN,1") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [exit-FAILED@macro-vm:2] GotoIf("SIP/300-00000123", "0?exit-RETURN,1") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [exit-FAILED@macro-vm:3] Hangup("SIP/300-00000123", "") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [exit-FAILED@macro-vm:3] Hangup("SIP/300-00000123", "") in new stack [2013-05-28 17:59:56] VERBOSE[26069] app_macro.c: == Spawn extension (macro-vm, exit-FAILED, 3) exited non-zero on 'SIP/300-00000123' in macro 'vm' [2013-05-28 17:59:56] VERBOSE[26069] app_macro.c: == Spawn extension (macro-vm, exit-FAILED, 3) exited non-zero on 'SIP/300-00000123' in macro 'vm' [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: == Spawn extension (ext-local, vmu301, 1) exited non-zero on 'SIP/300-00000123' [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: == Spawn extension (ext-local, vmu301, 1) exited non-zero on 'SIP/300-00000123' [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [h@ext-local:1] Macro("SIP/300-00000123", "hangupcall,") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [h@ext-local:1] Macro("SIP/300-00000123", "hangupcall,") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/300-00000123", "1?theend") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/300-00000123", "1?theend") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Goto (macro-hangupcall,s,3) [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Goto (macro-hangupcall,s,3) [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/300-00000123", "0?Set(CDR(recordingfile)=)") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/300-00000123", "0?Set(CDR(recordingfile)=)") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/300-00000123", "") in new stack [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/300-00000123", "") in new stack [2013-05-28 17:59:56] VERBOSE[26069] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/300-00000123' in macro 'hangupcall' [2013-05-28 17:59:56] VERBOSE[26069] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/300-00000123' in macro 'hangupcall' [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/300-00000123' [2013-05-28 17:59:56] VERBOSE[26069] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/300-00000123' [2013-05-28 17:59:56] VERBOSE[1554] chan_sip.c: == Extension Changed 300[ext-local] new state Idle for Notify User 315 [2013-05-28 17:59:56] VERBOSE[1554] chan_sip.c: == Extension Changed 300[ext-local] new state Idle for Notify User 315

Well I’m not going to be a douche about it, but it is always a good idea to read your logs when you’re having problems. Or while you’re posting them online…

[2013-05-28 17:59:53] VERBOSE[26069] app_voicemail.c: -- Recording was 0 seconds long but needs to be at least 12 - abandoning

Go check your voicemail.conf options for message length.

And to further reduce your confusion after reading your logs. you might notice everything in there is duplicated. Remove your duplicate logger entries in

/etc/asterisk/logger* files or you will go blind.

I have not altered the logger*.conf files so I have no idea what to remove. Can you be more explicit?

As you may not have suspected, I set the minimum message length in order to eliminate short hang up messages. If, as we suspect, this causes the error then it may well be a bug. Sometimes the obvious is not the answer.

The duplicated lines.

In which file? And why are they duplicated?

http://www.freepbx.org/forum/general-help/211-upgrade-complete

I’ll go post this as a bug.