ASTERISK crashes when call recording is set to "always on"

NSSL · May 12, 2009, 1:01pm

Hi ,

I am running FPBX version 2.5.1.5 and asterisk 1.14.18 - When I set call recording to “always on” on my extsnsion asterisk crashes intermittently

Anybody have any suggestions !

regards

fskrotzki · May 12, 2009, 3:12pm

Post a call trace showing the crash please. At the same time how about a lot more details of your setup. Is this a distro? IF so who’s patches, etc. If this is a hand build what, who’s directions and what didn’t you do or added extra, etc…

NSSL · May 12, 2009, 4:16pm

Hi - Thank you for your reply …

I am running Centos 5 ( 2.6.18-92.1.22.el5) - standard install on Dell PC - Everything is working fine until I turn on call recording !- Below plase find sample “full” log file - ( see 12:30:24 - asterisk stops and start again at 12:30:29) - How do I set up a call trace for more info if required ?

regards

bin/dialparties.agi
[May 12 12:30:04] VERBOSE[21228] logger.c: == Begin MixMonitor Recording mISDN/4-u76
[May 12 12:30:04] VERBOSE[21204] logger.c: dialparties.agi: Starting New Dialparties.agi
[May 12 12:30:04] VERBOSE[21230] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 12 12:30:04] VERBOSE[21230] logger.c: Found
[May 12 12:30:04] VERBOSE[21230] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 12 12:30:04] VERBOSE[21230] logger.c: Found
[May 12 12:30:04] VERBOSE[21230] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 12 12:30:04] VERBOSE[21230] logger.c: Found
[May 12 12:30:04] VERBOSE[21230] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[May 12 12:30:04] VERBOSE[21204] logger.c: dialparties.agi: Caller ID name is ‘6725939’ number is ‘6725939’
[May 12 12:30:04] VERBOSE[21204] logger.c: > dialparties.agi: Setting Alert-Info:
[May 12 12:30:04] VERBOSE[21204] logger.c: – AGI Script Executing Application: (SIPAddHeader) Options: (Alert-Info:)
[May 12 12:30:04] VERBOSE[21204] logger.c: dialparties.agi: USE_CONFIRMATION: ‘FALSE’
[May 12 12:30:04] VERBOSE[21204] logger.c: dialparties.agi: RINGGROUP_INDEX: ‘’
[May 12 12:30:04] VERBOSE[21204] logger.c: dialparties.agi: Methodology of ring is ‘ringall’
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Added extension 110 to extension map
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Added extension 116 to extension map
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Added extension 118 to extension map
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Extension 110 cf is disabled
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Extension 116 cf is disabled
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Extension 118 cf is disabled
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Extension 110 do not disturb is disabled
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Extension 116 do not disturb is disabled
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Extension 118 do not disturb is disabled
[May 12 12:30:04] VERBOSE[21204] logger.c: > dialparties.agi: extnum 110 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: dbset CALLTRACE/110 to 6725939
[May 12 12:30:04] VERBOSE[21204] logger.c: > dialparties.agi: extnum 116 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: dbset CALLTRACE/116 to 6725939
[May 12 12:30:04] VERBOSE[21204] logger.c: > dialparties.agi: extnum 118 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: dbset CALLTRACE/118 to 6725939
[May 12 12:30:04] VERBOSE[21204] logger.c: – dialparties.agi: Filtered ARG3: 110-116-118
[May 12 12:30:04] VERBOSE[21204] logger.c: > dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trwWM(auto-blkvm)
[May 12 12:30:04] VERBOSE[21204] logger.c: > dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trwWM(auto-blkvm)
[May 12 12:30:04] VERBOSE[21230] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[May 12 12:30:04] VERBOSE[21204] logger.c: – AGI Script dialparties.agi completed, returning 0
[May 12 12:30:04] DEBUG[21204] app_macro.c: Executed application: AGI
[May 12 12:30:04] VERBOSE[21204] logger.c: – Executing [s@macro-dial:7] Dial(“mISDN/4-u76”, “SIP/110&SIP/116&SIP/118|10|trwWM(auto-blkvm)”) in new stack
[May 12 12:30:04] VERBOSE[17497] logger.c: Extension Changed 110[ext-local] new state Ringing for Notify User 116 (queued)
[May 12 12:30:04] VERBOSE[21204] logger.c: – Called 110
[May 12 12:30:04] VERBOSE[21204] logger.c: – Called 116
[May 12 12:30:04] VERBOSE[17497] logger.c: Extension Changed 118[ext-local] new state InUse&Ringing for Notify User 116
[May 12 12:30:04] VERBOSE[21204] logger.c: – Called 118
[May 12 12:30:04] VERBOSE[21204] logger.c: – SIP/110-0914e7d8 is ringing
[May 12 12:30:04] VERBOSE[17500] logger.c: – Got SIP response 486 “Busy Here” back from 192.168.7.116
[May 12 12:30:04] VERBOSE[17500] logger.c: – Got SIP response 486 “Busy Here” back from 192.168.7.118
[May 12 12:30:04] VERBOSE[21204] logger.c: – SIP/116-0914fd68 is busy
[May 12 12:30:04] VERBOSE[21204] logger.c: – SIP/118-0921eae8 is busy
[May 12 12:30:04] VERBOSE[17497] logger.c: Extension Changed 118[ext-local] new state InUse for Notify User 116
[May 12 12:30:08] VERBOSE[21231] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 12 12:30:08] VERBOSE[21231] logger.c: Found
[May 12 12:30:08] VERBOSE[21231] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 12 12:30:08] VERBOSE[21231] logger.c: Found
[May 12 12:30:08] VERBOSE[21231] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 12 12:30:08] VERBOSE[21231] logger.c: Found
[May 12 12:30:08] VERBOSE[21231] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[May 12 12:30:09] VERBOSE[21231] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[May 12 12:30:15] VERBOSE[21204] logger.c: – Nobody picked up in 10000 ms
[May 12 12:30:15] VERBOSE[17497] logger.c: Extension Changed 110[ext-local] new state Idle for Notify User 116 (queued)
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: Dial
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-dial:8] Set(“mISDN/4-u76”, “DIALSTATUS=NOANSWER”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: Set
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-dial:9] GosubIf(“mISDN/4-u76”, “0?NOANSWER|1”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: GosubIf
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [600@ext-group:14] Set(“mISDN/4-u76”, “RingGroupMethod=”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [600@ext-group:15] GotoIf(“mISDN/4-u76”, “0?nodest”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [600@ext-group:16] Set(“mISDN/4-u76”, “__NODEST=”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [600@ext-group:17] DBdel(“mISDN/4-u76”, “BLKVM/600/mISDN/4-u76”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – DBdel: family=BLKVM, key=600/mISDN/4-u76
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [600@ext-group:18] Goto(“mISDN/4-u76”, “ext-group|601|1”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Goto (ext-group,601,1)
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:1] Macro(“mISDN/4-u76”, “user-callerid|”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:1] Set(“mISDN/4-u76”, “AMPUSER=6725939”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: Set
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:2] GotoIf(“mISDN/4-u76”, “0?report”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: GotoIf
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:3] ExecIf(“mISDN/4-u76”, “0|Set|REALCALLERIDNUM=6725939”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: ExecIf
[May 12 12:30:15] DEBUG[21204] func_db.c: DB: DEVICE/6725939/user not found in database.
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:4] Set(“mISDN/4-u76”, “AMPUSER=”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: Set
[May 12 12:30:15] DEBUG[21204] func_db.c: DB: AMPUSER//cidname not found in database.
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:5] Set(“mISDN/4-u76”, “AMPUSERCIDNAME=”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: Set
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:6] GotoIf(“mISDN/4-u76”, “1?report”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Goto (macro-user-callerid,s,11)
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: GotoIf
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:11] GotoIf(“mISDN/4-u76”, “0?continue”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: GotoIf
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:12] Set(“mISDN/4-u76”, “__TTL=63”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: Set
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:13] GotoIf(“mISDN/4-u76”, “1?continue”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Goto (macro-user-callerid,s,20)
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: GotoIf
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-user-callerid:20] NoOp(“mISDN/4-u76”, “Using CallerID “6725939” <6725939>”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: Noop
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:2] GotoIf(“mISDN/4-u76”, “0?skipdb”) in new stack
[May 12 12:30:15] DEBUG[21204] func_db.c: DB: BLKVM/600/mISDN/4-u76 not found in database.
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:3] GotoIf(“mISDN/4-u76”, “0?skipov”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:4] Set(“mISDN/4-u76”, “__NODEST=”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:5] Set(“mISDN/4-u76”, “__BLKVM_OVERRIDE=BLKVM/601/mISDN/4-u76”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:6] Set(“mISDN/4-u76”, “__BLKVM_BASE=601”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:7] Set(“mISDN/4-u76”, “DB(BLKVM/601/mISDN/4-u76)=TRUE”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:8] Set(“mISDN/4-u76”, “RRNODEST=”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:9] Set(“mISDN/4-u76”, “__NODEST=601”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:10] Set(“mISDN/4-u76”, “RecordMethod=Group”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:11] Macro(“mISDN/4-u76”, “record-enable|110-116-117-118|Group”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-record-enable:1] GotoIf(“mISDN/4-u76”, “1?check”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Goto (macro-record-enable,s,4)
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: GotoIf
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-record-enable:4] AGI(“mISDN/4-u76”, “recordingcheck|20090512-123015|1242127790.186”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[May 12 12:30:15] VERBOSE[21204] logger.c: recordingcheck|20090512-123015|1242127790.186: Recording enable for 110
[May 12 12:30:15] VERBOSE[21204] logger.c: recordingcheck|20090512-123015|1242127790.186: CALLFILENAME=g110-20090512-123015-1242127790.186
[May 12 12:30:15] VERBOSE[21204] logger.c: – AGI Script recordingcheck completed, returning 0
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: AGI
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-record-enable:999] MixMonitor(“mISDN/4-u76”, “g110-20090512-123015-1242127790.186.wav||”) in new stack
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: MixMonitor
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:12] Set(“mISDN/4-u76”, “RingGroupMethod=ringall”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [601@ext-group:13] Macro(“mISDN/4-u76”, “dial|10|trwW|110-116-117-118”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-dial:1] GotoIf(“mISDN/4-u76”, “1?dial”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Goto (macro-dial,s,3)
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: GotoIf
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-dial:3] AGI(“mISDN/4-u76”, “dialparties.agi”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[May 12 12:30:15] VERBOSE[21240] logger.c: == Begin MixMonitor Recording mISDN/4-u76
[May 12 12:30:15] VERBOSE[21204] logger.c: dialparties.agi: Starting New Dialparties.agi
[May 12 12:30:15] VERBOSE[21242] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 12 12:30:15] VERBOSE[21242] logger.c: Found
[May 12 12:30:15] VERBOSE[21242] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 12 12:30:15] VERBOSE[21242] logger.c: Found
[May 12 12:30:15] VERBOSE[21242] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 12 12:30:15] VERBOSE[21242] logger.c: Found
[May 12 12:30:15] VERBOSE[21242] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[May 12 12:30:15] VERBOSE[21204] logger.c: dialparties.agi: Caller ID name is ‘6725939’ number is ‘6725939’
[May 12 12:30:15] VERBOSE[21204] logger.c: > dialparties.agi: Setting Alert-Info:
[May 12 12:30:15] VERBOSE[21204] logger.c: – AGI Script Executing Application: (SIPAddHeader) Options: (Alert-Info:)
[May 12 12:30:15] VERBOSE[21204] logger.c: dialparties.agi: USE_CONFIRMATION: ‘FALSE’
[May 12 12:30:15] VERBOSE[21204] logger.c: dialparties.agi: RINGGROUP_INDEX: ‘’
[May 12 12:30:15] VERBOSE[21204] logger.c: dialparties.agi: Methodology of ring is ‘ringall’
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Added extension 110 to extension map
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Added extension 116 to extension map
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Added extension 117 to extension map
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Added extension 118 to extension map
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 110 cf is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 116 cf is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 117 cf is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 118 cf is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 110 do not disturb is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 116 do not disturb is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 117 do not disturb is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Extension 118 do not disturb is disabled
[May 12 12:30:15] VERBOSE[21204] logger.c: > dialparties.agi: extnum 110 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: dbset CALLTRACE/110 to 6725939
[May 12 12:30:15] VERBOSE[21204] logger.c: > dialparties.agi: extnum 116 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: dbset CALLTRACE/116 to 6725939
[May 12 12:30:15] VERBOSE[21204] logger.c: > dialparties.agi: extnum 117 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: dbset CALLTRACE/117 to 6725939
[May 12 12:30:15] VERBOSE[21204] logger.c: > dialparties.agi: extnum 118 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: dbset CALLTRACE/118 to 6725939
[May 12 12:30:15] VERBOSE[21204] logger.c: – dialparties.agi: Filtered ARG3: 110-116-117-118
[May 12 12:30:15] VERBOSE[21204] logger.c: > dialparties.agi: NODEST: 601 adding M(auto-blkvm) to dialopts: trwWM(auto-blkvm)
[May 12 12:30:15] VERBOSE[21204] logger.c: > dialparties.agi: NODEST: 601 blkvm enabled macro already in dialopts: trwWM(auto-blkvm)
[May 12 12:30:15] VERBOSE[21242] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[May 12 12:30:15] VERBOSE[21204] logger.c: – AGI Script dialparties.agi completed, returning 0
[May 12 12:30:15] DEBUG[21204] app_macro.c: Executed application: AGI
[May 12 12:30:15] VERBOSE[21204] logger.c: – Executing [s@macro-dial:7] Dial(“mISDN/4-u76”, “SIP/110&SIP/116&SIP/117&SIP/118|10|trwWM(auto-blkvm)”) in new stack
[May 12 12:30:15] VERBOSE[21204] logger.c: – Called 110
[May 12 12:30:15] VERBOSE[21204] logger.c: – Called 116
[May 12 12:30:15] VERBOSE[21204] logger.c: – Called 117
[May 12 12:30:15] VERBOSE[21204] logger.c: – Called 118
[May 12 12:30:15] VERBOSE[17500] logger.c: – Got SIP response 486 “Busy Here” back from 192.168.7.116
[May 12 12:30:15] VERBOSE[17497] logger.c: Extension Changed 110[ext-local] new state Ringing for Notify User 116 (queued)
[May 12 12:30:15] VERBOSE[17497] logger.c: Extension Changed 117[ext-local] new state Ringing for Notify User 116 (queued)
[May 12 12:30:15] VERBOSE[17497] logger.c: Extension Changed 118[ext-local] new state InUse&Ringing for Notify User 116
[May 12 12:30:15] VERBOSE[21204] logger.c: – SIP/116-0922e3e8 is busy
[May 12 12:30:15] VERBOSE[17500] logger.c: – Got SIP response 486 “Busy Here” back from 192.168.7.118
[May 12 12:30:15] VERBOSE[21204] logger.c: – SIP/118-09223d10 is busy
[May 12 12:30:15] VERBOSE[21204] logger.c: – SIP/117-0921ee68 is ringing
[May 12 12:30:15] VERBOSE[21204] logger.c: – SIP/117-0921ee68 is ringing
[May 12 12:30:15] VERBOSE[21204] logger.c: – SIP/110-09229908 is ringing
[May 12 12:30:15] VERBOSE[17497] logger.c: Extension Changed 118[ext-local] new state InUse for Notify User 116 (queued)
[May 12 12:30:15] VERBOSE[21204] logger.c: – SIP/110-09229908 is ringing
[May 12 12:30:15] VERBOSE[21243] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 12 12:30:15] VERBOSE[21243] logger.c: Found
[May 12 12:30:15] VERBOSE[21243] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 12 12:30:15] VERBOSE[21243] logger.c: Found
[May 12 12:30:15] VERBOSE[21243] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 12 12:30:15] VERBOSE[21243] logger.c: Found
[May 12 12:30:15] VERBOSE[21243] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[May 12 12:30:16] VERBOSE[21243] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[May 12 12:30:19] VERBOSE[21251] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 12 12:30:19] VERBOSE[21251] logger.c: Found
[May 12 12:30:19] VERBOSE[21251] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 12 12:30:19] VERBOSE[21251] logger.c: Found
[May 12 12:30:19] VERBOSE[21251] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 12 12:30:19] VERBOSE[21251] logger.c: Found
[May 12 12:30:19] VERBOSE[21251] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[May 12 12:30:19] VERBOSE[21251] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[May 12 12:30:21] WARNING[17500] chan_sip.c: Host ‘192.168.7.125’ does not implement ‘NOTIFY’
[May 12 12:30:23] VERBOSE[21254] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 12 12:30:23] VERBOSE[21254] logger.c: Found
[May 12 12:30:23] VERBOSE[21254] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 12 12:30:23] VERBOSE[21254] logger.c: Found
[May 12 12:30:23] VERBOSE[21254] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 12 12:30:23] VERBOSE[21254] logger.c: Found
[May 12 12:30:23] VERBOSE[21254] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[May 12 12:30:24] VERBOSE[21254] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[May 12 12:30:29] VERBOSE[21301] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log
[May 12 12:30:29] VERBOSE[21301] logger.c: Asterisk Dynamic Loader Starting:
[May 12 12:30:29] VERBOSE[21301] logger.c: == Parsing ‘/etc/asterisk/modules.conf’: [May 12 12:30:29] VERBOSE[21301] logger.c: Found
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Ping
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Events
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Logoff
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Hangup
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Status
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Setvar
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Getvar
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action GetConfig
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action UpdateConfig
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Redirect
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Originate
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action Command
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action ExtensionState
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action AbsoluteTimeout
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action MailboxStatus
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action MailboxCount
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action ListCommands
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action UserEvent
[May 12 12:30:29] VERBOSE[21301] logger.c: == Manager registered action WaitEvent
[May 12 12:30:29] VERBOSE[21301] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 12 12:30:29] VERBOSE[21301] logger.c: Found
[May 12 12:30:29] VERBOSE[21301] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 12 12:30:29] VERBOSE[21301] logger.c: Found
[May 12 12:30:29] VERBOSE[21301] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 12 12:30:29] VERBOSE[21301] logger.c: Found
[May 12 12:30:29] VERBOSE[21301] logger.c: Asterisk Management interface listening on port 5038
[May 12 12:30:29] NOTICE[21301] cdr.c: CDR simple logging enabled.
[May 12 12:30:29] VERBOSE[21301] logger.c: == Parsing ‘/etc/asterisk/rtp.conf’: [May 12 12:30:29] VERBOSE[21301] logger.c: Found
[May 12 12:30:29] VERBOSE[21301] logger.c: == RTP Allocating from port range 10000 -> 20000
[May 12 12:30:29] VERBOSE[21301] logger.c: == UDPTL allocating from port range 4500 -> 4999
[May 12 12:30:29] VERBOSE[21301] logger.c: Asterisk PBX Core Initializing
[May 12 12:30:29] VERBOSE[21301] logger.c: Registering builtin applications:
[May 12 12:30:29] VERBOSE[21301] logger.c: [Answer]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘Answer’
[May 12 12:30:29] VERBOSE[21301] logger.c: [BackGround]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘BackGround’
[May 12 12:30:29] VERBOSE[21301] logger.c: [Busy]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘Busy’
[May 12 12:30:29] VERBOSE[21301] logger.c: [Congestion]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘Congestion’
[May 12 12:30:29] VERBOSE[21301] logger.c: [Goto]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘Goto’
[May 12 12:30:29] VERBOSE[21301] logger.c: [GotoIf]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘GotoIf’
[May 12 12:30:29] VERBOSE[21301] logger.c: [GotoIfTime]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘GotoIfTime’
[May 12 12:30:29] VERBOSE[21301] logger.c: [ExecIfTime]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘ExecIfTime’
[May 12 12:30:29] VERBOSE[21301] logger.c: [Hangup]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘Hangup’
[May 12 12:30:29] VERBOSE[21301] logger.c: [NoOp]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘NoOp’
[May 12 12:30:29] VERBOSE[21301] logger.c: [Progress]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘Progress’
[May 12 12:30:29] VERBOSE[21301] logger.c: [ResetCDR]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘ResetCDR’
[May 12 12:30:29] VERBOSE[21301] logger.c: [Ringing]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘Ringing’
[May 12 12:30:29] VERBOSE[21301] logger.c: [SayNumber]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘SayNumber’
[May 12 12:30:29] VERBOSE[21301] logger.c: [SayDigits]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘SayDigits’
[May 12 12:30:29] VERBOSE[21301] logger.c: [SayAlpha]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application ‘SayAlpha’
[May 12 12:30:29] VERBOSE[21301] logger.c: [SayPhonetic]
[May 12 12:30:29] VERBOSE[21301] logger.c: == Registered application 'SayPhoneti

fskrotzki · May 12, 2009, 4:39pm

A call trace is done via the asterisk cli. Open a ssh session to the box then type:asterisk -rvvvvv
start capturing the output and make the crash happen. Post that information.

The full log while is does contain some information in this case does not contain anything of value. Sometimes when asterisk crashes it only has time to write the info to the current console before it closes.

How are you starting asterisk again? it should be run as the user asterisk and if it is run as root can cause strange things to happen.