[asterisk-dev] crash - Attempted to delete nonexistent schedule entry

Mon Oct 16 04:02:16 MST 2006

Hi,

Im trying to test asterisk 1.4 (multithreaded iax) - because of the 
performance problems of IAX in 1.2 versions.
Unfortunately it doesent seem to work stable for more just than a few 
minutes.

SVN-trunk-r45161 crashes on 'Attempted to delete nonexistent schedule 
entry': (should I submit a bug ?)

1)

[Oct 16 12:37:13] DEBUG[6946] res_config_mysql.c: MySQL RealTime: Updated 1 
rows on table: iax
[Oct 16 12:37:13] DEBUG[6951] res_config_mysql.c: MySQL RealTime: Everything 
is fine.
[Oct 16 12:37:13] DEBUG[6951] res_config_mysql.c: MySQL RealTime: Retrieve 
SQL: SELECT * FROM iax WHERE ipaddr = '87.207.221.170' AND port = '1113'
[Oct 16 12:37:13] DEBUG[6949] res_config_mysql.c: MySQL RealTime: Everything 
is fine.
[Oct 16 12:37:13] DEBUG[6949] res_config_mysql.c: MySQL RealTime: Update 
SQL: UPDATE iax SET ipaddr = '87.207.221.170', port = '1113', regseconds = 
'1160995033' WHERE name = '3248180'
[Oct 16 12:37:13] DEBUG[6949] res_config_mysql.c: MySQL RealTime: Updated 1 
rows on table: iax
[Oct 16 12:37:13] VERBOSE[6949] logger.c:     -- Registered IAX2 '3248180' 
(AUTHENTICATED) at 87.207.221.170:1113
[Oct 16 12:37:13] NOTICE[6949] chan_iax2.c: Restricting registration for 
peer '3248180' to 240 seconds (requested 300)
[Oct 16 12:37:13] NOTICE[7070] sched.c: Attempted to delete nonexistent 
schedule entry 4411!

..

#0  ast_sched_del (con=0x6aeaa0, id=4411) at sched.c:286
286                     CRASH;
(gdb) bt
#0  ast_sched_del (con=0x6aeaa0, id=4411) at sched.c:286
#1  0x0000002a9b15ee6d in schedule_delivery (fr=0x796f60, 
updatehistory=4934104, fromtrunk=7390048, tsout=0x40704ed8) at 
chan_iax2.c:2160
#2  0x0000002a9b1545d0 in socket_process (thread=0x796f60) at 
chan_iax2.c:7552
#3  0x0000002a9b14adf4 in iax2_process_thread (data=0x0) at chan_iax2.c:7604
#4  0x0000000000497275 in dummy_start (data=0x0) at utils.c:544
#5  0x0000003d12d057fb in start_thread () from /lib64/tls/libpthread.so.0
#6  0x0000003d113ba703 in thread_start () from /lib64/tls/libc.so.6
#7  0x0000000000000000 in ?? ()

and anoter one:

2)

#0  0x000000000048aa1b in ast_sched_del (con=0x6aeaa0, id=41115) at 
sched.c:286
286                     CRASH;
(gdb) bt
#0  0x000000000048aa1b in ast_sched_del (con=0x6aeaa0, id=41115) at 
sched.c:286
#1  0x0000002a9b15ea40 in iax2_destroy_helper (pvt=0x7e8a20) at 
chan_iax2.c:1670
#2  0x0000002a9b15026f in iax2_predestroy (callno=0) at chan_iax2.c:1683
#3  0x0000002a9b14a6c4 in send_command_final (i=0x7e8a20, type=6 '\006', 
command=5, ts=0, data=0x422e1670 "*\001", datalen=3, seqno=-1) at 
chan_iax2.c:4517
#4  0x0000002a9b149885 in iax2_hangup (c=0x7ea190) at chan_iax2.c:2910
#5  0x000000000042ff39 in ast_hangup (chan=0x7ea190) at channel.c:1557
#6  0x0000002a99a02bd7 in wait_for_answer (in=0x7e82f0, outgoing=0x7dfa70, 
to=0x422e26b4, peerflags=0x422e3064, sentringing=0x422e26b8,
    status=0x422e2e80 "NOANSWER", statussize=256, busystart=0, 
nochanstart=0, congestionstart=0, priority_jump=0, result=0xffffffff) at 
app_dial.c:592
#7  0x0000002a999ffcf6 in dial_exec_full (chan=0x7e82f0, data=0x422e2e80, 
peerflags=0x422e3064) at app_dial.c:1240
#8  0x0000002a999fe7d6 in dial_exec (chan=0x0, data=0x4b4998) at 
app_dial.c:1653
#9  0x000000000046c0a0 in pbx_exec (c=0x7e82f0, app=0x69da80, 
data=0x422e359a) at pbx.c:503
#10 0x0000002a9e00e4ed in handle_exec (chan=0x7e82f0, agi=0x422e3df0, 
argc=290481424, argv=0x422e3100) at res_agi.c:1104
#11 0x0000002a9e010892 in agi_handle_command (chan=0x7e82f0, agi=0x422e3df0, 
buf=0x0) at res_agi.c:1791
#12 0x0000002a9e0101ec in run_agi (chan=0x7e82f0, request=0x422e3e00 
"agi://10.0.1.246/outbound_call_manager.php?1=1720226&2=0129226&3=phoneast1",
    agi=0x422e3df0, pid=-1, status=0x422e3dd4, dead=1) at res_agi.c:1873
#13 0x0000002a9e00f81b in agi_exec_full (chan=0x7e82f0, data=0x0, 
enhanced=0, dead=1) at res_agi.c:2017
#14 0x000000000046c0a0 in pbx_exec (c=0x7e82f0, app=0x6d4ad0, 
data=0x422e4cc0) at pbx.c:503
#15 0x0000002a98fd668d in realtime_exec (chan=0x7e82f0, context=0x7e8540 
"from_gg", exten=0x7e8590 "0129226", priority=-1, callerid=0x3d11504ca3 "",
    data=0x0) at pbx_realtime.c:216
#16 0x000000000046e13d in pbx_extension_helper (c=0x7e82f0, con=0x7e82f0, 
context=0x7e8540 "from_bb", exten=0x7e8590 "0129226", priority=1, label=0x0,
    callerid=0x76ed00 "221234567", action=E_SPAWN) at pbx.c:1718
#17 0x000000000046e960 in __ast_pbx_run (c=0x7e82f0) at pbx.c:2162
#18 0x000000000046f569 in pbx_thread (data=0x0) at pbx.c:2475
#19 0x0000000000497235 in dummy_start (data=0x0) at utils.c:534
#20 0x0000003d12d057fb in start_thread () from /lib64/tls/libpthread.so.0
#21 0x0000003d113ba703 in thread_start () from /lib64/tls/libc.so.6
#22 0x0000000000000000 in ?? ()

1.4.0-beta2 on the other hand runs out of IAX threads and IAX hangs:

*CLI> iax2 show threads
IAX2 Thread Information
Idle Threads:
Active Threads:
Thread D17: state=2, update=84, actions=12, func ='socket_process'
Thread D19: state=2, update=84, actions=13, func ='socket_process'
...
Thread P7: state=2, update=86, actions=12145, func ='socket_process'
Dynamic Threads:
110 of 10 threads accounted for with 0 dynamic threads
*CLI>

Any chance to see stable scalable IAX any time soon ? ;)

Dawid