[asterisk-users] CPU Usage 100% when Voicemail Notification is sent

[Sip Support]

When anyone leaves a voicemail message and email notifications are enabled
it causes the cpu to go to consume 100% cpu indefinetly. Note that when
email notifications are not enabled, the issue is resolved. I have been able
to re-create the circumstances on every Asterisk
SVN-branch-1.6.0-r141627-/trunk as well as the most current trunk as of last
night. Asterisk is running on FreeBSD 7.1 and additional details are listed
below. Thank you for any help in advance that can help me in addressing this
issue.

PID USERNAME THR PRI NICE SIZE RES STATE TIME WCPU COMMAND

2945 root 44 96 0 28764K 16952K umtxn 0:00 97.06% asterisk

Occurs on any mailbox, only happens when email is enabled for a voicemail
box. Everything runs fine when email voicemail is disabled. Tried noload
chan_alsa.so and chan_oss.so to no avail.

logger.conf has "messages => notice,warning,error,debug,verbose" enabled and
nothing is showing besides normal output is appearing in the log.

Output from gbd

(gdb) info thread

44 Thread 0x8201100 (LWP 100183) 0x2841d7ef in poll () from /lib/libc.so.7

43 Thread 0x8201300 (LWP 100093) 0x28393037 in __error () from
/lib/libthr.so.3

42 Thread 0x8201400 (LWP 100094) 0x2841d7ef in poll () from /lib/libc.so.7

41 Thread 0x8201500 (LWP 100095) 0x28393037 in __error () from
/lib/libthr.so.3

40 Thread 0x8201600 (LWP 100096) 0x28393037 in __error () from
/lib/libthr.so.3

39 Thread 0x8201700 (LWP 100097) 0x28393037 in __error () from
/lib/libthr.so.3

38 Thread 0x8201800 (LWP 100098) 0x284724d3 in select () from /lib/libc.so.7

37 Thread 0x8201900 (LWP 100099) 0x28393037 in __error () from
/lib/libthr.so.3

36 Thread 0x8201a00 (LWP 100100) 0x28393037 in __error () from
/lib/libthr.so.3

35 Thread 0x8201b00 (LWP 100101) 0x28393037 in __error () from
/lib/libthr.so.3

34 Thread 0x8201c00 (LWP 100102) 0x28393037 in __error () from
/lib/libthr.so.3

33 Thread 0x8201d00 (LWP 100103) 0x28393037 in __error () from
/lib/libthr.so.3

32 Thread 0x8201e00 (LWP 100104) 0x28393037 in __error () from
/lib/libthr.so.3

31 Thread 0x8201f00 (LWP 100105) 0x28393037 in __error () from
/lib/libthr.so.3

30 Thread 0x8202000 (LWP 100106) 0x28393037 in __error () from
/lib/libthr.so.3

29 Thread 0x8202100 (LWP 100107) 0x28393037 in __error () from
/lib/libthr.so.3

28 Thread 0x8202200 (LWP 100108) 0x28393037 in __error () from
/lib/libthr.so.3

27 Thread 0x8202300 (LWP 100109) 0x28393037 in __error () from
/lib/libthr.so.3

26 Thread 0x8202400 (LWP 100110) 0x2841d7ef in poll () from /lib/libc.so.7

25 Thread 0x8202600 (LWP 100111) 0x2841d7ef in poll () from /lib/libc.so.7

24 Thread 0x8202700 (LWP 100112) 0x2841d7ef in poll () from /lib/libc.so.7

23 Thread 0x8202800 (LWP 100113) 0x2840f29f in accept () from /lib/libc.so.7

22 Thread 0x8202900 (LWP 100114) 0x2841d7ef in poll () from /lib/libc.so.7

21 Thread 0x8202a00 (LWP 100115) 0x2841d7ef in poll () from /lib/libc.so.7

20 Thread 0x8202b00 (LWP 100116) 0x28453f5f in nanosleep () from
/lib/libc.so.7

19 Thread 0x8202c00 (LWP 100117) 0x28453f5f in nanosleep () from
/lib/libc.so.7

18 Thread 0x8202e00 (LWP 100118) 0x28393037 in __error () from
/lib/libthr.so.3

17 Thread 0x8203100 (LWP 100236) 0x283db78b in _umtx_op () from
/lib/libc.so.7

16 Thread 0x8203200 (LWP 100237) 0x283db78b in _umtx_op () from
/lib/libc.so.7

15 Thread 0x8203300 (LWP 100238) 0x28bf51fc in iax2_process_thread
(data=0x8527000) at chan_iax2.c:9595

14 Thread 0x8203400 (LWP 100239) 0x283db78b in _umtx_op () from
/lib/libc.so.7

13 Thread 0x8203500 (LWP 100240) 0x283db78b in _umtx_op () from
/lib/libc.so.7

12 Thread 0x8203600 (LWP 100241) 0x283db78b in _umtx_op () from
/lib/libc.so.7

11 Thread 0x8203700 (LWP 100242) 0x283db78b in _umtx_op () from
/lib/libc.so.7

10 Thread 0x8203800 (LWP 100243) 0x283db78b in _umtx_op () from
/lib/libc.so.7

9 Thread 0x8203900 (LWP 100244) 0x283db78b in _umtx_op () from
/lib/libc.so.7

8 Thread 0x8203a00 (LWP 100245) 0x283db78b in _umtx_op () from
/lib/libc.so.7

7 Thread 0x8203b00 (LWP 100246) 0x283db78b in _umtx_op () from
/lib/libc.so.7

6 Thread 0x8203c00 (LWP 100247) 0x283db78b in _umtx_op () from
/lib/libc.so.7

5 Thread 0x8203d00 (LWP 100248) 0x283db78b in _umtx_op () from
/lib/libc.so.7

4 Thread 0x8203e00 (LWP 100249) 0x283db78b in _umtx_op () from
/lib/libc.so.7

3 Thread 0x8203f00 (LWP 100250) 0x283db78b in _umtx_op () from
/lib/libc.so.7

2 Thread 0x8204000 (LWP 100251) 0x283db78b in _umtx_op () from
/lib/libc.so.7

* 1 Thread 0x8204100 (LWP 100252) 0x283db789 in _umtx_op () from
/lib/libc.so.7

(gdb) thread apply all bt

Thread 44 (Thread 0x8201100 (LWP 100183)):

#0 0x2841d7ef in poll () from /lib/libc.so.7

#1 0x28389802 in poll () from /lib/libthr.so.3

#2 0x0806f482 in main (argc=1, argv=0xbfbfed28) at asterisk.c:2786

#3 0x0805a589 in ?? ()

#4 0x00000001 in ?? ()

#5 0xbfbfed28 in ?? ()

#6 0xbfbfed30 in ?? ()

#7 0x00000000 in ?? ()

#8 0x2815c6d0 in dlclose () from /libexec/ld-elf.so.1

#9 0x00000001 in ?? ()

Thread 43 (Thread 0x8201300 (LWP 100093)):

#0 0x28393037 in __error () from /lib/libthr.so.3

#1 0x28392d9a in __error () from /lib/libthr.so.3

#2 0x08206820 in ?? ()

#3 0x00000008 in ?? ()

#4 0x00000001 in ?? ()

#5 0x08206800 in ?? ()

#6 0x00000000 in ?? ()

#7 0x2839496c in ?? () from /lib/libthr.so.3

#8 0xbf9c1f28 in ?? ()

#9 0x2839154e in pthread_cond_init () from /lib/libthr.so.3

Previous frame identical to this frame (corrupt stack?)

#0 0x283db789 in _umtx_op () from /lib/libc.so.7

Starting asterisk with /usr/local/sbin/asterisk -vvvvvv -g -dddddd -c gives
this output

-- Accepting AUTHENTICATED call from xx.xx.xx.xx:

> requested format = ulaw,

> requested prefs = (ulaw|alaw|gsm|g729|ilbc),

> actual format = ulaw,

> host prefs = (ulaw|gsm),

> priority = mine

-- Executing [s at xxxxxxxxxxxx_incoming:1] Dial("IAX2/xxxxx,20,r") in new
stack

-- Called xxxxxx

-- Call accepted by 192.168.0.100 (format ulaw)

-- Format for call is ulaw

-- IAX2/xxxxx-4661 is ringing

-- Nobody picked up in 20000 ms

-- Hungup 'IAX2/xxxxx-4661'

-- Executing [s at xxxxxxxxxxxxxx_incoming:2]
VoiceMail("IAX2/xxxxxxxxxx-15804", "1000,u") in new stack

-- <IAX2/xxxxxxxx-15804> Playing
'/var/spool/asterisk/voicemail/default/1000/unavail.slin' (language 'en')

-- <IAX2/xxxxxxxx-15804> Playing 'vm-intro.ulaw' (language 'en')

-- <IAX2/xxxxxxxx-15804> Playing 'beep.ulaw' (language 'en')

-- Recording the message

-- x=0, open writing: /var/spool/asterisk/voicemail/default/1000/tmp/0FzabG
format: wav49, 0x820b470

-- x=1, open writing: /var/spool/asterisk/voicemail/default/1000/tmp/0FzabG
format: gsm, 0x8204800

-- x=2, open writing: /var/spool/asterisk/voicemail/default/1000/tmp/0FzabG
format: wav, 0x821cc00

-- User hung up

== Spawn extension (xxxxxxxxxxxx_incoming, s, 2) exited non-zero on
'IAX2/xxxxxxxxxx-15804'

-- Hungup 'IAX2/xxxxxxxxxxxx'

Additional Info: This error occurs whether you send to a user at localhost
or a remote user. /var/log/messages or /var/log/maillog does not contain any
errors or loops either. If you need any additional information to help me
resolve this I would be more than glad to provide it. Thanks in Advance for
any help.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20080908/ccdc5eb8/attachment.htm