CLI log:

*CLI>     -- Executing Answer("SIP/5050-081d6890", "") in new stack
    -- Executing Set("SIP/5050-081d6890", "CHANNEL(language)=pt_BR") in new stack
    -- Executing VoiceMailMain("SIP/5050-081d6890", "@default") in new stack
    -- Playing 'vm-login' (language 'pt_BR')
    -- Playing 'vm-password' (language 'pt_BR')
    -- Playing 'vm-youhave' (language 'pt_BR')
    -- Playing 'digits/2' (language 'pt_BR')
    -- Playing 'vm-INBOX' (language 'pt_BR')
    -- Playing 'vm-first' (language 'pt_BR')
    -- Playing 'vm-message' (language 'pt_BR')
Dec 23 01:08:14 NOTICE[25545]: app_voicemail.c:932 retrieve_file: res = -1; colsize = 10
Dec 23 01:08:14 NOTICE[25545]: app_voicemail.c:943 retrieve_file: Proceeding with reading out the data in small chunks
Dec 23 01:08:14 NOTICE[25545]: app_voicemail.c:945 retrieve_file: Offset = 0
Dec 23 01:08:14 NOTICE[25545]: app_voicemail.c:954 retrieve_file: res = -1, colsize2 =
  == Parsing '/var/spool/asterisk/voicemail/default/5050/INBOX/msg0000.txt': Found
    -- Playing 'vm-received' (language 'pt_BR')
    -- Playing 'digits/at' (language 'pt_BR')
    -- Playing 'digits/12' (language 'pt_BR')
    -- Playing 'digits/30' (language 'pt_BR')
    -- Playing 'digits/1' (language 'pt_BR')
    -- Playing 'digits/a-m' (language 'pt_BR')
Dec 23 01:08:18 NOTICE[25545]: sched.c:234 ast_sched_add_variable: Scheduled event in 0 ms?
    -- Playing 'vm-from-phonenumber' (language 'pt_BR')
    -- Playing 'digits/5' (language 'pt_BR')
    -- Playing 'digits/0' (language 'pt_BR')
    -- Playing 'digits/5' (language 'pt_BR')
    -- Playing 'digits/0' (language 'pt_BR')
  == Spawn extension (extensions, *98, 3) exited non-zero on 'SIP/5050-081d6890'
    -- Executing Goto("SIP/5050-081d6890", "hangupcall|s|1") in new stack
    -- Goto (hangupcall,s,1)
    -- Executing ResetCDR("SIP/5050-081d6890", "w") in new stack
       > cdr_odbc: Query Successful!
    -- Executing NoCDR("SIP/5050-081d6890", "") in new stack
Dec 23 01:08:22 NOTICE[25545]: cdr.c:443 ast_cdr_free: CDR on channel 'SIP/5050-081d6890' not posted
Dec 23 01:08:22 NOTICE[25545]: cdr.c:445 ast_cdr_free: CDR on channel 'SIP/5050-081d6890' lacks end
    -- Executing Wait("SIP/5050-081d6890", "1") in new stack
Dec 23 01:08:23 NOTICE[25536]: chan_sip.c:11181 handle_request_register: Registration from '<sip:500@172.16.0.187>' failed for '172.16.0.228' - Username/auth name mismatch
    -- Executing Hangup("SIP/5050-081d6890", "") in new stack
  == Spawn extension (hangupcall, s, 4) exited non-zero on 'SIP/5050-081d6890'


Full log:


Dec 23 01:08:18 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format slin
Dec 23 01:08:18 VERBOSE[25545] logger.c:     -- Playing 'vm-from-phonenumber' (language 'pt_BR')
Dec 23 01:08:20 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format ulaw
Dec 23 01:08:20 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format slin
Dec 23 01:08:20 VERBOSE[25545] logger.c:     -- Playing 'digits/5' (language 'pt_BR')
Dec 23 01:08:20 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format ulaw
Dec 23 01:08:20 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format slin
Dec 23 01:08:20 VERBOSE[25545] logger.c:     -- Playing 'digits/0' (language 'pt_BR')
Dec 23 01:08:21 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format ulaw
Dec 23 01:08:21 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format slin
Dec 23 01:08:21 VERBOSE[25545] logger.c:     -- Playing 'digits/5' (language 'pt_BR')
Dec 23 01:08:22 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format ulaw
Dec 23 01:08:22 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format slin
Dec 23 01:08:22 VERBOSE[25545] logger.c:     -- Playing 'digits/0' (language 'pt_BR')
Dec 23 01:08:22 DEBUG[25545] channel.c: Set channel SIP/5050-081d6890 to write format ulaw
Dec 23 01:08:22 WARNING[25545] file.c: File /var/spool/asterisk/voicemail/default/5050/INBOX/msg0000 does not exist in any format
Dec 23 01:08:22 WARNING[25545] file.c: Unable to open /var/spool/asterisk/voicemail/default/5050/INBOX/msg0000 (format ulaw): No such file or directory
Dec 23 01:08:22 DEBUG[25545] app.c: Locked path '/var/spool/asterisk/voicemail/default/5050/INBOX'
Dec 23 01:08:22 DEBUG[25545] app.c: Locked path '/var/spool/asterisk/voicemail/default/5050/Old'
Dec 23 01:08:22 WARNING[25545] app_voicemail.c: SQL Execute error!
[DELETE FROM tb_voicemail_messages WHERE dir=? AND msgnum=?]

Dec 23 01:08:22 DEBUG[25545] app.c: Unlocked path '/var/spool/asterisk/voicemail/default/5050/Old'
Dec 23 01:08:22 WARNING[25545] res_odbc.c: SQL Execute returned an error -1: 42P01: Error while executing the query;
ERROR:  relation "voicemessages" does not exist
CONTEXT:  PL/pgSQL function "vm_lo_cleanup" line 11 at select into variables (157)
Dec 23 01:08:22 WARNING[25545] res_odbc.c: SQL Execute returned an error -1: 00000: Error while executing the query;
ERROR:  relation "voicemessages" does not exist
CONTEXT:  PL/pgSQL function "vm_lo_cleanup" line 11 at select into variables (157)
Dec 23 01:08:22 WARNING[25545] app_voicemail.c: SQL Execute error!
[DELETE FROM tb_voicemail_messages WHERE dir=? AND msgnum=?]

Dec 23 01:08:22 DEBUG[25545] app.c: Unlocked path '/var/spool/asterisk/voicemail/default/5050/INBOX'