[asterisk-users] Problem with blank/empty voicemails

[Freddi Hansen]

Hi,
I hope for a hint on this issue.

I had a voicemail running on ast release 1.6.2 latest which i upgraded 
to 1.8.11 latest release.
during this process I did add a couple of fields like minsecs and maxsecs.

I do now get empty emails where the attached voicefile only contains the 
voice header,
the message length written in the email is ok.
If I go to the voicemailbox during the recording then I can se the files 
grow to the filesize i would expect, looks like everything is ok until then.

When I press the '#' or hangup then the email is generated with the 
empty attachment and the voicefiles in the INBOX is now
44 bytes for .wav and
60 byes for .WAV

I have voicemail in mysql and messages stored i filesystem
here is some of the config (which worked ok on release 1.6.2)
attachfmt=wav
deletevoicemail=no
volgain=0.0
minsecs=2
maxsecs=600

[Apr 23 21:59:46] DEBUG[31841] app.c: Locked path 
'/var/spool/asterisk/voicemail/default/88855/INBOX'
[Apr 23 21:59:46] DEBUG[31841] app.c: Unlocked path 
'/var/spool/asterisk/voicemail/default/88855/INBOX'
[Apr 23 21:59:46] DEBUG[31841] channel.c: Scheduling timer at (50 
requested / 50 actual) timer ticks per second
[Apr 23 21:59:46] VERBOSE[31841] file.c:     -- 
<SIP/_Mw_cHFm6vZAyV-00012a5c> Playing 'beep.alaw' (language 'dk')
[Apr 23 21:59:46] DEBUG[31841] channel.c: Scheduling timer at (182 
requested / 182 actual) timer ticks per second
[Apr 23 21:59:46] DEBUG[31841] channel.c: Scheduling timer at (0 
requested / 0 actual) timer ticks per second
[Apr 23 21:59:46] DEBUG[31841] channel.c: Scheduling timer at (0 
requested / 0 actual) timer ticks per second
[Apr 23 21:59:46] DEBUG[31841] channel.c: Scheduling timer at (0 
requested / 0 actual) timer ticks per second
[Apr 23 21:59:46] VERBOSE[31841] app_voicemail.c:     -- Recording the 
message
[Apr 23 21:59:46] DEBUG[31841] app.c: play_and_record: <None>, 
/var/spool/asterisk/voicemail/default/88855/tmp/N0NNlv, 'wav49|gsm|wav'
[Apr 23 21:59:46] DEBUG[31841] app.c: Recording Formats: sfmts=wav49
[Apr 23 21:59:46] VERBOSE[31841] app.c:     -- x=0, open writing:  
/var/spool/asterisk/voicemail/default/88855/tmp/N0NNlv format: wav49, 
0xb3501378
[Apr 23 21:59:46] VERBOSE[31841] app.c:     -- x=1, open writing:  
/var/spool/asterisk/voicemail/default/88855/tmp/N0NNlv format: gsm, 
0xb266b1b0
[Apr 23 21:59:46] VERBOSE[31841] app.c:     -- x=2, open writing:  
/var/spool/asterisk/voicemail/default/88855/tmp/N0NNlv format: wav, 
0xb3519948
[Apr 23 21:59:46] DEBUG[31841] dsp.c: Setup tone 1100 Hz, 500 ms, 
block_size=160, hits_required=21
[Apr 23 21:59:46] DEBUG[31841] dsp.c: Setup tone 2100 Hz, 2600 ms, 
block_size=160, hits_required=116
[Apr 23 21:59:46] DEBUG[31841] channel.c: Set channel 
SIP/_Mw_cHFm6vZAyV-00012a5c to read format slin
[Apr 23 22:00:03] VERBOSE[31841] app.c:     -- User hung up
[Apr 23 22:00:03] DEBUG[31841] channel.c: Set channel 
SIP/_Mw_cHFm6vZAyV-00012a5c to read format alaw
[Apr 23 22:00:03] DEBUG[31841] app.c: Locked path 
'/var/spool/asterisk/voicemail/default/88855/INBOX'
[Apr 23 22:00:03] DEBUG[31841] app.c: Unlocked path 
'/var/spool/asterisk/voicemail/default/88855/INBOX'
[Apr 23 22:00:03] DEBUG[31841] app_voicemail.c: Attaching file 
'/var/spool/asterisk/voicemail/default/88855/INBOX/msg0000', format 
'wav', uservm is '2048', global is 2048
[Apr 23 22:00:03] VERBOSE[31841] config.c:   == Parsing 
'/var/spool/asterisk/voicemail/default/88855/INBOX/msg0000.txt': [Apr 23 
22:00:03] DEBUG[31841] config.c: Parsing 
/var/spool/asterisk/voicemail/default/88855/INBOX/msg0000.txt
[Apr 23 22:00:03] VERBOSE[31841] config.c:   == Found
[Apr 23 22:00:03] VERBOSE[31841] config.c:   == Parsing 
'/var/spool/asterisk/voicemail/default/88855/INBOX/msg0000.txt': [Apr 23 
22:00:03] DEBUG[31841] config.c: Parsing 
/var/spool/asterisk/voicemail/default/88855/INBOX/msg0000.txt
[Apr 23 22:00:03] VERBOSE[31841] config.c:   == Found
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Evaluating 'VM_NAME' (from 'VM_NAME}:
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Result of 'VM_NAME' is 'Freddi Hansen'
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Evaluating 'VM_DUR' (from 'VM_DUR} 
lang besked (number ${VM_MSGNUM})
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Result of 'VM_DUR' is '0:16'
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Evaluating 'VM_MSGNUM' (from 
'VM_MSGNUM})
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Result of 'VM_MSGNUM' is '1'
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Evaluating 'VM_MAILBOX' (from 
'VM_MAILBOX} fra ${VM_CALLERID}, den ${VM_DATE}.  Tak!
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Result of 'VM_MAILBOX' is '88855'
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Evaluating 'VM_CALLERID' (from 
'VM_CALLERID}, den ${VM_DATE}.  Tak!
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Result of 'VM_CALLERID' is 
'"voicemailtest" <_Mw_cHFm6vZAyV>'
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Evaluating 'VM_DATE' (from 
'VM_DATE}.  Tak!
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Result of 'VM_DATE' is 'Monday, 23 
April 2012 at 22:00:03'
[Apr 23 22:00:03] DEBUG[31841] app_voicemail.c: creating attachment 
filename msg0000.wav, no second attachment.
[Apr 23 22:00:03] DEBUG[31841] app_voicemail.c: Sent mail to 
fh at danovation.dk with command '/usr/sbin/sendmail -t'
[Apr 23 22:00:03] DEBUG[31841] pbx.c: Spawn extension 
(voicemailtest,222,2) exited non-zero on 'SIP/_Mw_cHFm6vZAyV-00012a5c'