[asterisk-bugs] [Asterisk 0012140]: Attempted to delete nonexistent schedule entry

noreply at bugs.digium.com noreply at bugs.digium.com
Wed Mar 5 00:49:14 CST 2008


A NOTE has been added to this issue. 
====================================================================== 
http://bugs.digium.com/view.php?id=12140 
====================================================================== 
Reported By:                slavon
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   12140
Category:                   Channels/chan_sip/General
Reproducibility:            always
Severity:                   tweak
Priority:                   normal
Status:                     new
Asterisk Version:           SVN 
SVN Branch (only for SVN checkouts, not tarball releases):  trunk 
SVN Revision (number only!): 106010 
Disclaimer on File?:        N/A 
Request Review:              
====================================================================== 
Date Submitted:             03-04-2008 23:32 CST
Last Modified:              03-05-2008 00:49 CST
====================================================================== 
Summary:                    Attempted to delete nonexistent schedule entry
Description: 
In DEBUG messages i see many messages like this:
ast_sched_del: Attempted to delete nonexistent schedule entry 0!

i look to backtrace and see this:
http://bugs.digium.com/view.php?id=0  ast_sched_del (con=0xb7629a20, id=0) at
sched.c:291
http://bugs.digium.com/view.php?id=1  0xb6f970fd in __sip_reliable_xmit
(p=0x8216c08, seqno=102, resp=0,
    data=0xb6795c48 "INVITE sip:6624658 at 87.255.0.218 SIP/2.0\r\nVia:
SIP/2.0/UDP 87.255.9.250:5060;branch=z9hG4bK1abe39d1;rport\r\nMax-Forwards:
70\r\nFrom: \"4955021766\"
<sip:4955021766 at 87.255.9.250>;tag=as745a2efb\r\nTo: <sip:66"..., len=863,
fatal=1,
    sipmethod=5) at chan_sip.c:2824
http://bugs.digium.com/view.php?id=2  0xb6f9870d in send_request (p=0x8216c08,
req=0xb6795a24,
reliable=XMIT_CRITICAL, seqno=102) at chan_sip.c:3083
http://bugs.digium.com/view.php?id=3  0xb6fb158d in transmit_invite
(p=0x8216c08, sipmethod=5, sdp=1,
init=2) at chan_sip.c:8705
http://bugs.digium.com/view.php?id=4  0xb6f9d492 in sip_call (ast=0x821ad20,
dest=0xb6797a78 "6624658 at voip",
timeout=0) at chan_sip.c:4170
http://bugs.digium.com/view.php?id=5  0x0808e8b9 in ast_call (chan=0x821ad20,
addr=0xb6797a78
"6624658 at voip", timeout=0) at channel.c:3392
http://bugs.digium.com/view.php?id=6  0xb749869c in dial_exec_full
(chan=0x8215678, data=0xb6799eb8,
peerflags=0xb6797d3c, continue_exec=0x0) at app_dial.c:1533
http://bugs.digium.com/view.php?id=7  0xb749b18c in dial_exec (chan=0x8215678,
data=0xb6799eb8) at
app_dial.c:1964
http://bugs.digium.com/view.php?id=8  0x080ddac6 in pbx_exec (c=0x8215678,
app=0x81d2498, data=0xb6799eb8)
at pbx.c:728
http://bugs.digium.com/view.php?id=9  0x080e2dd2 in pbx_extension_helper
(c=0x8215678, con=0x0,
context=0x82157fc "zap-g1-in", exten=0x821584c "6624658",
    priority=1, label=0x0, callerid=0x8211f08 "4955021766",
action=E_SPAWN, found=0xb679c2fc, combined_find_spawn=1)
    at pbx.c:2699
http://bugs.digium.com/view.php?id=10 0x080e415a in ast_spawn_extension
(c=0x8215678, context=0x82157fc
"zap-g1-in", exten=0x821584c "6624658", priority=1,
    callerid=0x8211f08 "4955021766", found=0xb679c2fc,
combined_find_spawn=1) at pbx.c:3190
http://bugs.digium.com/view.php?id=11 0x080e486a in __ast_pbx_run (c=0x8215678)
at pbx.c:3289
http://bugs.digium.com/view.php?id=12 0x080e59f8 in pbx_thread (data=0x8215678)
at pbx.c:3551
http://bugs.digium.com/view.php?id=13 0x0812b002 in dummy_start (data=0x8212088)
at utils.c:870
http://bugs.digium.com/view.php?id=14 0xb7cd918b in start_thread () from
/lib/libpthread.so.0
http://bugs.digium.com/view.php?id=15 0xb7db476e in clone () from /lib/libc.so.6

then i look to code chan_sip.c:2824 and look this macros:
AST_SCHED_REPLACE_VARIABLE(pkt->retransid, sched, siptimer_a, retrans_pkt,
pkt, 1);

look up and see what pkt defined in this function, but pkt->retransid not
defined. 

Its bug?

Tested on last 1.4 SVN and TRUNK svn

Thanks
====================================================================== 

---------------------------------------------------------------------- 
 slavon - 03-05-08 00:49  
---------------------------------------------------------------------- 
its interesting for me because i see in DEBUG and messages like this

[Mar  5 09:40:13] DEBUG[12754]: chan_sip.c:7965 add_sdp: ** Our
capability: 0xc (ulaw|alaw) Video flag: True Text flag: True
[Mar  5 09:40:13] DEBUG[12754]: chan_sip.c:7966 add_sdp: ** Our prefcodec:
0x0 (nothing)
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1134 channel_find_locked:
Failure, could not lock '0x82c2b90' after 9 retries!
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Mar  5 09:40:13] DEBUG[12754]: channel.c:1129 channel_find_locked:
Avoiding initial deadlock for channel '0x82c2b90'
[Mar  5 09:40:13] DEBUG[12754]: sched.c:291 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!

also in code sched.c:291 next line do CRUSH and if i aright this must say
that its critical error?

if its not bug - sorry, but looks fear for me =)) 

Issue History 
Date Modified   Username       Field                    Change               
====================================================================== 
03-05-08 00:49  slavon         Note Added: 0083403                          
======================================================================




More information about the asterisk-bugs mailing list