[Home]

Summary:ASTERISK-00305: The attached voicemail2 box crashes asterisk
Reporter:oacyscom (oacyscom)Labels:
Date Opened:2003-09-24 17:38:47Date Closed:2004-09-25 02:21:18
Priority:CriticalRegression?No
Status:Closed/CompleteComponents:Core/General
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:( 0) 1100.tgz
Description:Every time I try to check my mail asterisk crashes. This occurs with both X-Lite and a SNOM 200. The attached file is the mailbox in question.

****** ADDITIONAL INFORMATION ******

== Console ==
   -- Executing VoiceMailMain2("SIP/ernest-d843", "") in new stack
   -- Playing 'vm-login'
WARNING[1133735216]: File chan_sip.c, Line 443 (retrans_pkt): Maximum retries exceeded on call 4649d13a28006a782790882a3a0ac023@208.19.48.161 for seqno 102 (Request)
   -- Playing 'vm-youhave'
   -- Playing 'digits/1'
   -- Playing 'vm-INBOX'
   -- Playing 'vm-message'
   -- Playing 'vm-onefor'
   -- Playing 'vm-INBOX'
   -- Playing 'vm-messages'
   -- Playing 'vm-opts'
   -- Playing 'vm-first'
   -- Playing 'vm-message'
 == Parsing '/var/spool/asterisk/voicemail/default/1100/INBOX/msg0000.txt': Found
Segmentation fault
Ouch ... error while writing audio data: : Broken pipe

== tail -n 30 /var/log/asterisk/debug ==
Sep 24 15:10:31 DEBUG[1200876848]: File rtp.c, Line 942 (ast_rtp_raw_write): Difference is 28776, ms is 3617
Sep 24 15:10:31 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 160 sample intervals
Sep 24 15:10:32 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:32 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:32 DEBUG[1200876848]: File rtp.c, Line 165 (send_dtmf): Sending dtmf: 52 (4)
Sep 24 15:10:33 DEBUG[1200876848]: File rtp.c, Line 165 (send_dtmf): Sending dtmf: 50 (2)
Sep 24 15:10:33 DEBUG[1200876848]: File rtp.c, Line 165 (send_dtmf): Sending dtmf: 49 (1)
Sep 24 15:10:33 DEBUG[1200876848]: File rtp.c, Line 165 (send_dtmf): Sending dtmf: 51 (3)
Sep 24 15:10:33 DEBUG[1133735216]: File chan_sip.c, Line 493 (__sip_autodestruct): Auto destroying call '5968dd1716f7a8bf234f0e9905d90bfc@208.19.48.161'
Sep 24 15:10:34 DEBUG[1133735216]: File chan_sip.c, Line 493 (__sip_autodestruct): Auto destroying call '61619046228b4f4c7ef01d1d2c06992b@208.19.48.161'
Sep 24 15:10:35 DEBUG[1200876848]: File rtp.c, Line 942 (ast_rtp_raw_write): Difference is 28560, ms is 3590
Sep 24 15:10:35 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 160 sample intervals
Sep 24 15:10:36 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:36 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:36 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 160 sample intervals
Sep 24 15:10:37 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:37 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:37 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 160 sample intervals
Sep 24 15:10:37 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:37 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:37 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 160 sample intervals
Sep 24 15:10:38 DEBUG[1200876848]: File rtp.c, Line 165 (send_dtmf): Sending dtmf: 49 (1)
Sep 24 15:10:38 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:38 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 160 sample intervals
Sep 24 15:10:38 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:38 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:38 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 160 sample intervals
Sep 24 15:10:39 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:39 DEBUG[1200876848]: File channel.c, Line 953 (ast_settimeout): Scheduling timer at 0 sample intervals
Sep 24 15:10:39 DEBUG[1200876848]: File say.c, Line 255 (ast_say_date_with_format): ast_say_date_with_format() called

== tail /var/log/asterisk/message ==
Sep 24 15:07:05 WARNING[1074406560]: File chan_iax2.c, Line 5134 (set_config): Ignoring port for now
Sep 24 15:07:11 WARNING[1133735216]: File chan_sip.c, Line 443 (retrans_pkt): Maximum retries exceeded on call 7947ece4197b4dd64830e4f72c9ac557@208.19.48.161 for seqno 102 (Request)
Sep 24 15:07:11 WARNING[1133735216]: File chan_sip.c, Line 443 (retrans_pkt): Maximum retries exceeded on call 7827910960a8de810f4b5eb734247b1d@208.19.48.161 for seqno 102 (Request)
Sep 24 15:07:56 WARNING[1133735216]: File chan_sip.c, Line 443 (retrans_pkt): Maximum retries exceeded on call 5d9a3b956a0f5e21186e87ed3e10c1e2@208.19.48.161 for seqno 102 (Request)
Sep 24 15:08:35 WARNING[1200876848]: File app_voicemail2.c, Line 2290 (vm_execmain): Couldn't read username
Sep 24 15:09:01 WARNING[1200876848]: File app_voicemail2.c, Line 2290 (vm_execmain): Couldn't read username
Sep 24 15:10:18 WARNING[1142127920]: File chan_oss.c, Line 232 (sound_thread): Read error on sound device: Resource temporarily unavailable
Sep 24 15:10:18 WARNING[1074406560]: File chan_iax2.c, Line 5134 (set_config): Ignoring port for now
Sep 24 15:10:24 WARNING[1133735216]: File chan_sip.c, Line 443 (retrans_pkt): Maximum retries exceeded on call 5968dd1716f7a8bf234f0e9905d90bfc@208.19.48.161 for seqno 102 (Request)
Sep 24 15:10:25 WARNING[1133735216]: File chan_sip.c, Line 443 (retrans_pkt): Maximum retries exceeded on call 61619046228b4f4c7ef01d1d2c06992b@208.19.48.161 for seqno 102 (Request)
Comments:By: Brian West (bkw918) 2003-09-24 21:04:13

http://bugs.digium.com/bug_view_page.php?bug_id=0000292

This has been fixed.  CVS Update and check the bug tracker before opening duplicate issues.

bkw
By: Mark Spencer (markster) 2003-09-25 08:20:22

Can you please confirm this has already been fixed for you with current CVS?
By: oacyscom (oacyscom) 2003-09-25 12:02:25

The problem occurred using a 9/24 CVS. The newest CVS (9/15 9:30A) seems to have killed SIP, so I can't test voicemail. Will do so when I get SIP working again.
By: Tilghman Lesher (tilghman) 2003-09-25 15:04:09

Can you post a backtrace?

gdb /usr/sbin/asterisk core.1234
<Enter><Enter><Enter>etc.
bt

and post the output of that 'bt'?
By: oacyscom (oacyscom) 2003-09-25 16:12:29

#0  0x420796ba in strcmp () from /lib/tls/libc.so.6
#1  0x080a75ec in localsub (timep=0x4793c70c, offset=1064001536, tmp=0x4793c6b8, zone=0x0) at localtime.c:985
#2  0x080a74c9 in ast_localtime (timep=0x3f6b6000, p_tm=0x4793c6b8, zone=0x0) at localtime.c:1044
#3  0x08061595 in ast_say_date_with_format (chan=0x8118cd8, time=1064001578, ints=0x41d4941f "0123456789#*",
   lang=0x8118d28 "en", format=0x41d492a0 "'vm-received' q 'digits/at' IMp", timezone=0x0) at say.c:257
#4  0x41d47be9 in play_message_datetime (chan=0x8118cd8, vmu=0x4793d608, vms=0x3f6b6000) at app_voicemail2.c:1958
ASTERISK-1  0x41d4475e in play_message (chan=0x8118cd8, vmu=0x3f6b6000, vms=0x4793d898, msg=0) at app_voicemail2.c:1986
ASTERISK-2  0x41d3edb2 in vm_execmain (chan=0x8118cd8, data=0x0) at app_voicemail2.c:2373
ASTERISK-3  0x08062e6a in pbx_exec (c=0x8118cd8, app=0x80f11e8, data=0x4793e5a8, newstack=1) at pbx.c:396
ASTERISK-4  0x0806a0b1 in pbx_extension_helper (c=0x8118cd8, context=0x8118e2c "local", exten=0x4793d898 "INBOX", priority=1,
   callerid=0x80d45c0 "\"Ernest W. Lessenger\" <559-781-9999>", action=0) at pbx.c:1151
ASTERISK-5  0x08064d2c in ast_pbx_run (c=0x0) at pbx.c:1635
ASTERISK-6 0x0806a771 in pbx_thread (data=0x3f6b6000) at pbx.c:1856
ASTERISK-7 0x40024332 in start_thread () from /lib/tls/libpthread.so.0
By: Tilghman Lesher (tilghman) 2003-09-25 16:23:14

You don't have the latest version of stdtime/localtime.c.  If you've ever synced a previous copy of the file, that version "sticks" in place.  You'll need to edit stdtime/CVS/Entries to remove the date or revision at the end of the line and resync CVS to get the latest version.
By: oacyscom (oacyscom) 2003-09-25 16:36:13

That fixed it, thanks.
By: Mark Spencer (markster) 2003-09-25 22:27:34

Already fixed in CVS