Voicemail Call Crashes Asterisk?

Hi,

This problem is really perplexing us. For some reason, our Elastix box simply dropped all in-progress calls and restarted Asterisk (without rebooting the server).

Here is the log file of the call which was “last before the crash”-- any ideas what could be causing the system to crash like this? The messages from the verbose full log immediately after this are the ones shown during a normal “startup” load for Asterisk.

[Oct 23 11:18:07] VERBOSE[1280] pbx.c: -- Executing [*[email protected]:1] Answer("SIP/4000-0000c3ec", "") in new stack [Oct 23 11:18:07] VERBOSE[1280] pbx.c: -- Executing [*[email protected]:2] Wait("SIP/4000-0000c3ec", "1") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [*[email protected]:3] Macro("SIP/4000-0000c3ec", "user-callerid,") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:1] Set("SIP/4000-0000c3ec", "AMPUSER=4000") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/4000-0000c3ec", "0?report") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/4000-0000c3ec", "1?Set(REALCALLERIDNUM=4000)") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:4] Set("SIP/4000-0000c3ec", "AMPUSER=4000") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:5] Set("SIP/4000-0000c3ec", "AMPUSERCIDNAME=Receptionist") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/4000-0000c3ec", "0?report") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:7] Set("SIP/4000-0000c3ec", "AMPUSERCID=4000") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:8] Set("SIP/4000-0000c3ec", "CALLERID(all)="Receptionist" <4000>") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/4000-0000c3ec", "0?limit") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:10] ExecIf("SIP/4000-0000c3ec", "0?Set(GROUP(concurrency_limit)=4000)") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:11] ExecIf("SIP/4000-0000c3ec", "0?Set(CHANNEL(language)=)") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/4000-0000c3ec", "0?continue") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:13] Set("SIP/4000-0000c3ec", "__TTL=64") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:14] GotoIf("SIP/4000-0000c3ec", "1?continue") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Goto (macro-user-callerid,s,25) [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:25] Set("SIP/4000-0000c3ec", "CALLERID(number)=4000") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:26] Set("SIP/4000-0000c3ec", "CALLERID(name)=Receptionist") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:27] Set("SIP/4000-0000c3ec", "CHANNEL(language)=en") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [*[email protected]:4] Macro("SIP/4000-0000c3ec", "get-vmcontext,4000") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:1] Set("SIP/4000-0000c3ec", "VMCONTEXT=default") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/4000-0000c3ec", "0?200:300") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Goto (macro-get-vmcontext,s,300) [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [[email protected]:300] NoOp("SIP/4000-0000c3ec", "") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [*[email protected]:5] Set("SIP/4000-0000c3ec", "VMBOXEXISTSSTATUS=SUCCESS") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [*[email protected]:6] GotoIf("SIP/4000-0000c3ec", "1?mbexist") in new stack [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Goto (from-internal,*97,106) [Oct 23 11:18:08] VERBOSE[1280] pbx.c: -- Executing [*[email protected]:106] VoiceMailMain("SIP/4000-0000c3ec", "[email protected]") in new stack [Oct 23 11:18:08] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-password.gsm' (language 'en') [Oct 23 11:18:11] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-youhave.gsm' (language 'en') [Oct 23 11:18:12] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-first.gsm' (language 'en') [Oct 23 11:18:12] VERBOSE[1280] config.c: == Parsing '/var/spool/asterisk/voicemail/default/4000/Urgent/msg0000.txt': [Oct 23 11:18:12] VERBOSE[1280] config.c: == Found [Oct 23 11:18:12] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-Urgent.gsm' (language 'en') [Oct 23 11:18:13] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-message.gsm' (language 'en') [Oct 23 11:18:14] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing '/var/spool/asterisk/voicemail/default/4000/Urgent/msg0000.slin' (language 'en') [Oct 23 11:18:14] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-advopts.gsm' (language 'en') [Oct 23 11:18:15] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-deleted.gsm' (language 'en') [Oct 23 11:18:16] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-last.gsm' (language 'en') [Oct 23 11:18:17] VERBOSE[1280] config.c: == Parsing '/var/spool/asterisk/voicemail/default/4000/Urgent/msg0001.txt': [Oct 23 11:18:17] VERBOSE[1280] config.c: == Found [Oct 23 11:18:17] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-Urgent.gsm' (language 'en') [Oct 23 11:18:18] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-message.gsm' (language 'en') [Oct 23 11:18:19] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing '/var/spool/asterisk/voicemail/default/4000/Urgent/msg0001.slin' (language 'en') [Oct 23 11:18:22] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-deleted.gsm' (language 'en') [Oct 23 11:18:24] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-prev.gsm' (language 'en') [Oct 23 11:18:25] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-first.gsm' (language 'en') [Oct 23 11:18:25] VERBOSE[1280] config.c: == Parsing '/var/spool/asterisk/voicemail/default/4000/INBOX/msg0000.txt': [Oct 23 11:18:25] VERBOSE[1280] config.c: == Found [Oct 23 11:18:25] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-message.gsm' (language 'en') [Oct 23 11:18:26] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing '/var/spool/asterisk/voicemail/default/4000/INBOX/msg0000.slin' (language 'en') [Oct 23 11:18:28] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-advopts.gsm' (language 'en') [Oct 23 11:18:29] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-undeleted.gsm' (language 'en') [Oct 23 11:18:30] VERBOSE[1280] config.c: == Parsing '/var/spool/asterisk/voicemail/default/4000/INBOX/msg0001.txt': [Oct 23 11:18:30] VERBOSE[1280] config.c: == Found [Oct 23 11:18:30] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-message.gsm' (language 'en') [Oct 23 11:18:31] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'digits/2.gsm' (language 'en') [Oct 23 11:18:32] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing '/var/spool/asterisk/voicemail/default/4000/INBOX/msg0001.slin' (language 'en') [Oct 23 11:18:32] WARNING[1280] app_voicemail.c: Playback of message /var/spool/asterisk/voicemail/default/4000/INBOX/msg0001 failed [Oct 23 11:18:32] WARNING[1280] file.c: Failed to write frame [Oct 23 11:18:32] VERBOSE[1280] file.c: -- <SIP/4000-0000c3ec> Playing 'vm-prev.gsm' (language 'en')