Summary: | ASTERISK-11680: asterisk crashes (core) when starting with menuselect DO_CRASH | ||
Reporter: | Michael_qq12345 (qq12345) | Labels: | |
Date Opened: | 2008-03-18 17:29:33 | Date Closed: | 2008-03-18 17:38:35 |
Priority: | Critical | Regression? | No |
Status: | Closed/Complete | Components: | 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 ... |