Summary:ASTERISK-07055: deadlock and, may be, memory leak
Reporter:caspy (caspy)Labels:
Date Opened:2006-05-30 07:08:56Date Closed:2006-05-30 09:17:10
Versions:Frequency of
Environment:Attachments:( 0) asterisk_bt_20060530_3.txt
Description:message appeared in console:

May 30 15:35:56 NOTICE[18351]: chan_sip.c:6753 get_refer_info: Supervised transfer attempted to transfer into same call id (759b61e8621916807d7a22b035190c33@masterhost.ru == 759b61e8621916807d7a22b035190c33@masterhost.ru)!

after this various threads begin to log:

May 30 15:35:59 WARNING[3712]: channel.c:787 channel_find_locked: Avoided initial deadlock for '0x4172b710', 10 retries!
May 30 15:36:00 WARNING[18346]: channel.c:787 channel_find_locked: Avoided initial deadlock for '0x4172b710', 10 retries!

in 'show channels' it looks like:
phone2*CLI> show channels
Channel              Location             State   Application(Data)
SIP/gatewayTDO-696e  (None)               Up      Bridged Call(nt-a5e8,2)
13 active channels
7 active calls

Some minutes later it changed to:
SIP/gatewayTDO-696e  (None)               Up      Bridged Call()

then to:
SIP/gatewayTDO-696e  (None)               Up      Bridged Call(&ASTERISK-1060;&ASTERISK-1049;&ASTERISK-1060;D)

then to:
SIP/gatewayTDO-696e  (None)               Up      Bridged Call(L&ASTERISK-1094;&ASTERISK-1060;&ASTERISK-9299;&ASTERISK-1057;&ASTERISK-9291;&ASTERISK-1059;&ASTERISK-9291;&ASTERISK-1063;&ASTERISK-9291;&ASTERISK-1063;&ASTERISK-9239;&ASTERISK-1063;(&ASTERISK-1063;&ASTERISK-9216;

then to:
SIP/gatewayTDO-696e  (None)               Up      Bridged Call(60;branch=z9hG4bK

then again to:
SIP/gatewayTDO-696e  (None)               Up      Bridged Call()


bt of this thread:

Thread 10 (Thread 1097857968 (LWP 3615)):
#0  0x40028436 in __lll_mutex_lock_wait () from /lib/tls/libpthread.so.0
#1  0x4002589f in _L_mutex_lock_73 () from /lib/tls/libpthread.so.0
#2  0x416f8678 in ?? ()
#3  0x40025a60 in pthread_mutex_unlock () from /lib/tls/libpthread.so.0
#4  0x08060da4 in ast_waitfor_nandfds (c=0x416f87f0, n=2, fds=0x0, nfds=0, exception=0x0, outfd=0x0, ms=0x416f87e4)
   at lock.h:592
ASTERISK-1  0x08069281 in ast_generic_bridge (c0=0x4110d4e8, c1=0x4172b710, config=0x416f8eb0, fo=0x416f8988, rc=0x416f898c,
   bridge_end={tv_sec = 0, tv_usec = 0}) at channel.c:1655
ASTERISK-2  0x080655fd in ast_channel_bridge (c0=0x4110d4e8, c1=0x4172b710, config=0x416f8eb0, fo=0x416f8988, rc=0x416f898c)
   at channel.c:3524
ASTERISK-3  0x40426d29 in ast_bridge_call (chan=0x4110d4e8, peer=0x4172b710, config=0x416f8eb0) at res_features.c:1319
ASTERISK-4  0x4096f301 in dial_exec_full (chan=0x4110d4e8, data=0x416f8eb0, peerflags=0x416f9568) at app_dial.c:1577
ASTERISK-5  0x4096ddc5 in dial_exec (chan=0xfffffffc, data=0xfffffffc) at app_dial.c:1619
ASTERISK-6 0x0808e445 in pbx_extension_helper (c=0x4110d4e8, con=0xfffffffc, context=0x4110d638 "toagent", exten=0x4110d72c "1107",
   priority=6, label=0x0, callerid=0x0, action=0) at pbx.c:553
ASTERISK-7 0x0808efea in __ast_pbx_run (c=0x4110d4e8) at pbx.c:2226
ASTERISK-8 0x0808fcdf in pbx_thread (data=0xfffffffc) at pbx.c:2513
ASTERISK-9 0x40023b63 in start_thread () from /lib/tls/libpthread.so.0
ASTERISK-10 0x401ab18a in clone () from /lib/tls/libc.so.6

no frame's can get now, cause this is production.
if it will live until night, i'll do more detailed bt.
Comments:By: caspy (caspy) 2006-05-30 07:53:25

Asterisk got in stuck just now.

All incoming calls from pri were dropped with:
May 30 16:40:06 WARNING[18355] chan_zap.c: Ring requested on channel 0/3 already in use on span 2.  Hanging up owner.

But in bt i can't find any zap related lock. bt attached.

By: Serge Vecher (serge-v) 2006-05-30 09:16:57

caspy: this is the second time I'm asking you to, please, do not open duplicate issues. Keep putting debugging information into bug 6997. Thank you.