[Home]

Summary:ASTERISK-24699: VoiceMailMain hangs up with no reason when trying to play first message
Reporter:Felix Kunsmann (Kunsi)Labels:
Date Opened:2015-01-16 08:22:08.000-0600Date Closed:2015-02-20 19:13:57.000-0600
Priority:MajorRegression?No
Status:Closed/CompleteComponents:Applications/app_voicemail
Versions:11.9.0 13.1.0 Frequency of
Occurrence		Constant
Related
Issues:
Environment:debian wheezy, Asterisk 13.1.0, snom 360 phones (8.7.3.25)Attachments:( 0) extensions.conf
( 1) msg0000.alaw
( 2) msg0000.gsm
( 3) msg0000.txt
( 4) msg0000.ulaw
( 5) msg0000.wav
( 6) sip.conf
( 7) sip-debug.txt
( 8) voicemail.conf
Description:I recorded a message in my voicemail box, then tried to listen to it using VoiceMailMain(). VoiceMailMain() plays greeting, I press 1 to listen to the message, Asterisk plays "First message", then hangs up.

WAV file sent to my by mail, and downloaded WAV file play fine in VLC.

URL contains SIP debug log provided by Asterisk.
Phone is 192.168.0.52
Asterisk is 192.168.0.20
Comments:By: Matt Jordan (mjordan) 2015-01-16 09:18:22.773-0600

Thanks for your comments. This does not appear to be a bug report and we are closing it. We appreciate the difficulties you are facing, but it would make more sense to raise your question in the support tracker, http://www.asterisk.org/support.


By: Felix Kunsmann (Kunsi) 2015-01-16 09:40:31.353-0600

Well, I think it maybe IS a bug, because there is no reason I can see to have Asterisk disconnect my call. There are no errors in SIP debug log, there is no error in sound files, there is no error in txt files, so where should the error be, when not in app_voicemail?
By: Matt Jordan (mjordan) 2015-01-16 10:38:58.974-0600

Re-opened per conversation on IRC.

{quote}
(09:55:00 AM) Kunsi: i don't have allow=all, if you're referring to allowed codecs in sip.conf
(09:55:19 AM) mjordan: you have a lot of codecs set up for that peer, regardless of what it happens to be
(09:55:42 AM) mjordan: your peer is offering g722,ulaw,alaw,gsm,aal2,g729
(09:55:50 AM) mjordan: configure your peer's codecs to match that.
(09:56:03 AM) mjordan: At a minimum, just do allow=!all,g722,ulaw,alaw,gsm
(09:56:38 AM) gerhard7 left the room (quit: Quit: Leaving).
(09:57:46 AM) Qwell left the room (quit: Changing host).
(09:57:46 AM) Qwell [north@asterisk/developer/Qwell] entered the room.
(09:57:46 AM) mode (+o Qwell) by ChanServ
(09:57:47 AM) Kunsi: arr, sh*t. moved allowed codecs from peer definition into general section, and "dis" got missing ☹
(09:57:57 AM) Kunsi: so, yeah, i had allow_all
(09:58:01 AM) Kunsi: allow=all*
(09:58:01 AM) bluesoul|work_v left the room (quit: Ping timeout: 256 seconds).
(09:58:58 AM) Kunsi: let me test that
(10:03:30 AM) outtolunc [~textual@c-67-170-214-55.hsd1.ca.comcast.net] entered the room.
(10:03:57 AM) [TK]D-Fender: mjordan: He didn't get a codec warning and every other sound file played. through VMM.  No statement of * even trying....
(10:06:12 AM) [TK]D-Fender: mjordan: is just ... stopped.
(10:08:59 AM) Vutral left the room (quit: Excess Flood).
(10:17:24 AM) Kunsi: mjordan: no, setting codecs does not resolve my issue
(10:17:49 AM) gusto [~gusto@2a02:810d:8640:248:8219:34ff:fecf:17f0] entered the room.
(10:20:23 AM) tparcina left the room (quit: Ping timeout: 240 seconds).
(10:20:49 AM) Akuma left the room (quit: Ping timeout: 252 seconds).
(10:21:23 AM) Kunsi: same as before: http://pastebin.com/Tt4kHKE2 - tried with an old and with an new recorded message
(10:23:06 AM) Vutral [~ss@mirbsd/special/Vutral] entered the room.
(10:23:53 AM) tedstriker [~tedstrike@host-135-196-33-208.lines.viateldsl.com] entered the room.
(10:24:18 AM) yokel left the room (quit: Ping timeout: 265 seconds).
(10:26:27 AM) yokel [~yokel@unaffiliated/contempt] entered the room.
(10:29:54 AM) mjordan: Kunsi: k, I'll reopen. Provide the full DEBUG log generated using the instructions on the wiki, along with 'sip set debug on'. Make sure you attach your sip.conf, extensions.conf, and voicemail.conf.
(10:30:17 AM) Kunsi: ok
(10:30:29 AM) mjordan: Do you have the issue on both 11 and 13?
(10:30:34 AM) mjordan: what specific version are you testing with?
(10:31:42 AM) Kunsi: i've had this issue with 11.9.0, but stopped looking at it since i didn't have asterisk connected to an actual phone line
(10:31:50 AM) dundel [~dundel@190.98.43.239] entered the room.
(10:32:06 AM) Kunsi: updated to 13.1.0 this week, connected an ATA, and tried to get voicemail working, but no success
(10:32:49 AM) Kunsi: 13.1. was downloaded and compiled yesterday, so it should be pretty up-to-date
(10:34:52 AM) yokel left the room (quit: Ping timeout: 245 seconds).
(10:37:49 AM) mjordan: k, so you did have it with 11 as well
(10:38:01 AM) mjordan: can you attach the .ulaw file that is supposed to be played back as well?
{quote}
By: Rusty Newton (rnewton) 2015-02-20 19:13:57.755-0600

Howdy!

I reproduced your issue using your config files. Fortunately it is not a bug, just a typo.

The issue is this:

{noformat}
newtonr-laptop*CLI>
   -- <SIP/76-00000000> Playing 'vm-first.gsm' (language 'en')
 == Parsing '/var/spool/asterisk/voicemail/default/800/INBOX/msg0000.txt': Found
   -- <SIP/76-00000000> Playing 'vm-message.gsm' (language 'en')
[Feb 20 19:02:35] WARNING[3938][C-00000000]: file.c:774 ast_openstream_full: File vm-recieved does not exist in any format
[Feb 20 19:02:35] WARNING[3938][C-00000000]: file.c:1100 ast_streamfile: Unable to open vm-recieved (format (ulaw)): No such file or directory
[Feb 20 19:02:35] WARNING[3938][C-00000000]: say.c:467 wait_file: Unable to play message vm-recieved
 == Spawn extension (internal, 98, 1) exited non-zero on 'SIP/76-00000000'
{noformat}

Notice the spelling "vm-recieved".

You have set this in your voicemail.conf file:

{noformat}
[zonemessages]
military=Zulu|'vm-recieved' q 'digits/at' H N 'hours' 'phonetic/z_p'
european=Europe/Berlin|'vm-recieved' a d b 'digits/at'HM
{noformat}

It should be *vm-received*, as shown in the sample config file:

{noformat}
eastern=America/New_York|'vm-received' Q 'digits/at' IMp
central=America/Chicago|'vm-received' Q 'digits/at' IMp
central24=America/Chicago|'vm-received' q 'digits/at' H N 'hours'
military=Zulu|'vm-received' q 'digits/at' H N 'hours' 'phonetic/z_p'
european=Europe/Copenhagen|'vm-received' a d b 'digits/at' HM
{noformat}

The end result of the mis-configuration causes the behavior you described.

You likely didn't see the "WARNING" messages that would have pointed you towards the problem due to mis-configuration in logger.conf.

Reading over the documentation on Asterisk's logging facilities will help you out in the future:

https://wiki.asterisk.org/wiki/display/AST/Logging
https://wiki.asterisk.org/wiki/display/AST/Logging+Configuration

Thanks!