[Home]

Summary:ASTERISK-11680: asterisk crashes (core) when starting with menuselect DO_CRASH
Reporter:Michael_qq12345 (qq12345)Labels:
Date Opened:2008-03-18 17:29:33Date Closed:2008-03-18 17:38:35
Priority:CriticalRegression?No
Status:Closed/CompleteComponents:Core/General
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:I update from SVN, always "head" from trunk.
This is my configure: ./configure --enable-dev-mode --disable-largefile --with-execinfo --with-misdn
I made in menuselect DONT_OPTIMIZE, DEBUG_THREADS, LOADABLE_MODULES, DEBUG_CHANNEL_LOCKS,
DEBUG_SCHEDULER, DEBUG_THREADLOCALS, DETECT_DEADLOCKS, DUMP_SCHEDULER,
MALLOC_DEBUG, TRACE_FRAMES, THREAD_CRASH, CHANNEL_TRACE
DO_CRASH
and started asterisk -cdfgvvvvvvvvvvvvvvvvvvvvvvvvvvvvv
mISDN 1.2

Then asterisk crashes, always.


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

3 traces:

a) x-lite started registering
[...]
app_exec.so => (Executes dialplan applications)
 == Registered application 'MacroExit'
 == Registered application 'MacroIf'
 == Registered application 'MacroExclusive'
 == Registered application 'Macro'
app_macro.so => (Extension Macros)
[Mar 18 22:59:22] DEBUG[25660]: pbx.c:5277 ast_context_find_or_create: Registered context 'app_dial_gosub_virtual_context'
   -- Registered extension context 'app_dial_gosub_virtual_context'
[Mar 18 22:59:22] DEBUG[25660]: pbx.c:6497 ast_add_extension2: Added extension 's' priority 1 to app_dial_gosub_virtual_context
   -- Added extension 's' priority 1 to app_dial_gosub_virtual_context
 == Registered application 'Dial'
 == Registered application 'RetryDial'
app_dial.so => (Dialing Application)
 == Registered custom function 'EXTENSION_STATE'
func_extstate.so => (Gets an extension's state in the dialplan)
 == Registered file format pcm, extension(s) pcm|ulaw|ul|mu
 == Registered file format alaw, extension(s) alaw|al
 == Registered file format au, extension(s) au
 == Registered file format g722, extension(s) g722
format_pcm.so => (Raw/Sun uLaw/ALaw 8KHz (PCM,PCMA,AU), G.722 16Khz)
res_limit.so => (Resource limits)
 == Registered application 'ControlPlayback'
app_controlplayback.so => (Control Playback Application)
 == Parsing '/etc/asterisk/codecs.conf': [Mar 18 22:59:22] DEBUG[25660]: config.c:1277 config_text_file_load: Parsing /etc/asterisk/codecs.conf
 == Found
   -- codec_ulaw: using generic PLC
 == Registered translator 'ulawtolin' from format ulaw to slin, cost 1
[Mar 18 22:59:22] DEBUG[25660]: translate.c:447 rebuild_matrix: Resetting translation matrix
 == Registered translator 'lintoulaw' from format slin to ulaw, cost 1
[Mar 18 22:59:22] DEBUG[25660]: translate.c:447 rebuild_matrix: Resetting translation matrix
codec_ulaw.so => (mu-Law Coder/Decoder)
 == Registered file format g723sf, extension(s) g723|g723sf
format_g723.so => (G.723.1 Simple Timestamp File Format)
 == Registered application 'SoftHangup'
app_softhangup.so => (Hangs up the requested channel)
[Mar 18 22:59:22] DEBUG[25660]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 22:59:22] DEBUG[25660]: sched.c:146 ast_sched_wait: ast_sched_wait()
[Mar 18 22:59:22] DEBUG[25660]: sched.c:270 ast_sched_del: ast_sched_del()
[Mar 18 22:59:22] DEBUG[25660]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (2 in Q, 7 Total, 3 Cache)
[Mar 18 22:59:22] DEBUG[25660]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:59:22] DEBUG[25660]: sched.c:323 ast_sched_dump: |0007 | 0xb66637e8      | 0x8234168       | 000003 : 894291 |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:323 ast_sched_dump: |0002 | 0xb66368be      | 0x8234168       | 003609 : 642017 |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: chan_sip.c:3016 __sip_ack: Stopping retransmission on '1cdcdc895fa088476e794cd61d6710f0@192.168.201.61' of Request 102: Match Found
[Mar 18 22:59:22] NOTICE[25660]: chan_sip.c:15078 handle_response_peerpoke: Peer '102' is now Reachable. (110ms / 2000ms)
[Mar 18 22:59:22] DEBUG[25660]: sched.c:270 ast_sched_del: ast_sched_del()
[Mar 18 22:59:22] DEBUG[25660]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (1 in Q, 7 Total, 4 Cache)
[Mar 18 22:59:22] DEBUG[25660]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:59:22] DEBUG[25660]: sched.c:323 ast_sched_dump: |0002 | 0xb66368be      | 0x8234168       | 003609 : 637267 |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: sched.c:219 ast_sched_add_variable: ast_sched_add()
[Mar 18 22:59:22] DEBUG[25660]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (2 in Q, 8 Total, 3 Cache)
[Mar 18 22:59:22] DEBUG[25660]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:59:22] DEBUG[25660]: sched.c:323 ast_sched_dump: |0008 | 0xb6636bc8      | 0x8234168       | 000059 : 999997 |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:323 ast_sched_dump: |0002 | 0xb66368be      | 0x8234168       | 003609 : 634227 |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 22:59:22] DEBUG[25660]: sched.c:270 ast_sched_del: ast_sched_del()
[Mar 18 22:59:22] DEBUG[25660]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (2 in Q, 8 Total, 3 Cache)
[Mar 18 22:59:22] DEBUG[25660]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:59:22] DEBUG[25660]: sched.c:323 ast_sched_dump: |0008 | 0xb6636bc8      | 0x8234168       | 000059 : 995970 |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:323 ast_sched_dump: |0002 | 0xb66368be      | 0x8234168       | 003609 : 630200 |
[Mar 18 22:59:22] DEBUG[25660]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:59:22] DEBUG[25660]: sched.c:291 ast_sched_del: Attempted to delete nonexistent schedule entry -1!
!! Forcing immediate crash a-la abort !!
Segmentation fault (core dumped)
pbxasterisk:~ #                  
------------------------------------------------------------------------------------------------------------------
x-lite started registering, AMR removed
[...]
format_ilbc.so => (Raw iLBC data)
 == Parsing '/etc/asterisk/codecs.conf': [Mar 18 22:56:58] DEBUG[24858]: config.c:1277 config_text_file_load: Parsing /etc/asterisk/codecs.conf
 == Found
   -- codec_amr: parsing codecs.conf
   -- codec_amr: set octed-aligned mode to 1
   -- codec_amr: set dtx mode to 0
   -- codec_amr: AMR mode set to MR122 (7)
codec_amr: enc_mode = 7, dtx = 0
 == Registered translator 'amrtolin' from format amr to slin, cost 4001
[Mar 18 22:56:58] DEBUG[24858]: translate.c:447 rebuild_matrix: Resetting translation matrix
[Mar 18 22:56:58] DEBUG[24858]: sched.c:270 ast_sched_del: ast_sched_del()
[Mar 18 22:56:58] DEBUG[24858]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (2 in Q, 7 Total, 3 Cache)
[Mar 18 22:56:58] DEBUG[24858]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:56:58] DEBUG[24858]: sched.c:323 ast_sched_dump: |0007 | 0xb670c7e8      | 0x829ba10       | 000003 : 895838 |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:323 ast_sched_dump: |0002 | 0xb66df8be      | 0x829ba10       | 003609 : 515059 |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: chan_sip.c:3016 __sip_ack: Stopping retransmission on '7861480f1424f19e2491e3936bdb57ab@192.168.201.61' of Request 102: Match Found
[Mar 18 22:56:58] NOTICE[24858]: chan_sip.c:15078 handle_response_peerpoke: Peer '102' is now Reachable. (107ms / 2000ms)
[Mar 18 22:56:58] DEBUG[24858]: sched.c:270 ast_sched_del: ast_sched_del()
[Mar 18 22:56:58] DEBUG[24858]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (1 in Q, 7 Total, 4 Cache)
[Mar 18 22:56:58] DEBUG[24858]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:56:58] DEBUG[24858]: sched.c:323 ast_sched_dump: |0002 | 0xb66df8be      | 0x829ba10       | 003609 : 511236 |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: sched.c:219 ast_sched_add_variable: ast_sched_add()
[Mar 18 22:56:58] DEBUG[24858]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (2 in Q, 8 Total, 3 Cache)
[Mar 18 22:56:58] DEBUG[24858]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:56:58] DEBUG[24858]: sched.c:323 ast_sched_dump: |0008 | 0xb66dfbc8      | 0x829ba10       | 000059 : 999997 |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:323 ast_sched_dump: |0002 | 0xb66df8be      | 0x829ba10       | 003609 : 508621 |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 22:56:58] DEBUG[24858]: sched.c:270 ast_sched_del: ast_sched_del()
[Mar 18 22:56:58] DEBUG[24858]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (2 in Q, 8 Total, 3 Cache)
[Mar 18 22:56:58] DEBUG[24858]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 22:56:58] DEBUG[24858]: sched.c:323 ast_sched_dump: |0008 | 0xb66dfbc8      | 0x829ba10       | 000059 : 996656 |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:323 ast_sched_dump: |0002 | 0xb66df8be      | 0x829ba10       | 003609 : 505280 |
[Mar 18 22:56:58] DEBUG[24858]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 22:56:58] DEBUG[24858]: sched.c:291 ast_sched_del: Attempted to delete nonexistent schedule entry -1!
!! Forcing immediate crash a-la abort !!
Segmentation fault (core dumped)
pbxasterisk:~ #                        
---------------------------------------------------------

x-lite not running, amr removed
[...]
[Mar 18 23:11:08] DEBUG[25741]: chan_sip.c:2531 __sip_xmit: Trying to put 'OPTIONS si' onto UDP socket...
[Mar 18 23:11:08] DEBUG[25741]: sched.c:146 ast_sched_wait: ast_sched_wait()
[Mar 18 23:11:08] DEBUG[25741]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 23:11:08] DEBUG[25741]: sched.c:146 ast_sched_wait: ast_sched_wait()
[Mar 18 23:11:09] DEBUG[25741]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 23:11:09] DEBUG[25741]: chan_sip.c:2531 __sip_xmit: Trying to put 'OPTIONS si' onto UDP socket...
[Mar 18 23:11:09] DEBUG[25741]: sched.c:146 ast_sched_wait: ast_sched_wait()
[Mar 18 23:11:09] DEBUG[25741]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 23:11:09] DEBUG[25741]: sched.c:146 ast_sched_wait: ast_sched_wait()
[Mar 18 23:11:10] DEBUG[25741]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 23:11:10] DEBUG[25741]: chan_sip.c:2531 __sip_xmit: Trying to put 'OPTIONS si' onto UDP socket...
[Mar 18 23:11:10] DEBUG[25741]: sched.c:146 ast_sched_wait: ast_sched_wait()
[Mar 18 23:11:10] DEBUG[25741]: sched.c:338 ast_sched_runq: ast_sched_runq()
[Mar 18 23:11:10] NOTICE[25741]: chan_sip.c:18848 sip_poke_noanswer: Peer '102' is now UNREACHABLE!  Last qualify: 0
[Mar 18 23:11:10] DEBUG[25741]: sched.c:270 ast_sched_del: ast_sched_del()
[Mar 18 23:11:10] DEBUG[25741]: sched.c:307 ast_sched_dump: Asterisk Schedule Dump (2 in Q, 7 Total, 2 Cache)
[Mar 18 23:11:10] DEBUG[25741]: sched.c:312 ast_sched_dump: =============================================================
[Mar 18 23:11:10] DEBUG[25741]: sched.c:313 ast_sched_dump: |ID    Callback          Data              Time  (sec:ms)   |
[Mar 18 23:11:10] DEBUG[25741]: sched.c:314 ast_sched_dump: +-----+-----------------+-----------------+-----------------+
[Mar 18 23:11:10] DEBUG[25741]: sched.c:323 ast_sched_dump: |0006 | 0xb666cbad      | 0x82cf308       | 000000 : 992785 |
[Mar 18 23:11:10] DEBUG[25741]: sched.c:323 ast_sched_dump: |0002 | 0xb668d8be      | 0x8211518       | 003605 : 775136 |
[Mar 18 23:11:10] DEBUG[25741]: sched.c:325 ast_sched_dump: =============================================================
[Mar 18 23:11:10] DEBUG[25741]: sched.c:291 ast_sched_del: Attempted to delete nonexistent schedule entry -1!
!! Forcing immediate crash a-la abort !!
Segmentation fault (core dumped)
------------------------------------------------


All tests are repeatable.
Comments:By: Russell Bryant (russell) 2008-03-18 17:38:33

Do not enable DO_CRASH ...