Asterisk hangs up when going to voicemail

scottay · January 28, 2014, 11:13pm

I’ve got a very annoying issue where some of my voicemails get recorded as just a dial tone. I have a feeling there’s something somewhere causing Asterisk to think that the caller hung up as the voicemail picks up, but since I’m pretty new to Asterisk and FreePBX I don’t know where to start looking. It’s not every single call, but it seems like a majority of them. Any time I personally call it always works. A few may be from people hanging up and not leaving a voicemail, but I know for a fact most of them are people really trying to leave voicemails (they usually leave one on my cell or call my cell and tell me they left a message).

I’m using a Digium TDM410P with an FXS and an FXO module. I use a Charter phone line. I’ve got it set up with a DAHDI Trunk. I’m running all this on an old Dell PowerEdge 800 server that I inherited from a client of mine. I’m using FreePBX 2.10.1.9, all my modules are up to date.

Any help would be great. Thanks!

david55 · January 29, 2014, 7:53am

Logs?

scottay · February 7, 2014, 6:55pm

Sorry for the delay. I’ve been super busy and out of the office a lot. I wanted to wait until I had a confirmed “hangup” voicemail where the customer DID leave a message (I asked her when I called back).

[2014-02-06 14:47:14] VERBOSE[19541] sig_analog.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19542] app_mixmonitor.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] res_agi.c: [2014-02-06 14:47:15] VERBOSE[19541] pbx.c: [2014-02-06 14:47:15] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:15] WARNING[19541] [2014-02-06 14:47:15] VERBOSE[19541] netsock2.c: [2014-02-06 14:47:15] VERBOSE[19541] netsock2.c: [2014-02-06 14:47:15] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:15] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:15] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:15] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:17] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:23] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:29] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:35] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:36] VERBOSE[19541] app_dial.c: [2014-02-06 14:47:36] VERBOSE[19541] sig_analog.c: [2014-02-06 14:47:36] VERBOSE[19541] chan_dahdi.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] app_mixmonitor.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: ck
[2014-02-06 14:47:36] VERBOSE[19542] app_mixmonitor.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] pbx.c: [2014-02-06 14:47:36] VERBOSE[19541] file.c: [2014-02-06 14:47:44] VERBOSE[19541] file.c: [2014-02-06 14:47:50] VERBOSE[19541] file.c: [2014-02-06 14:47:50] VERBOSE[19541] app_voicemail.c: [2014-02-06 14:47:50] VERBOSE[19541] app.c: [2014-02-06 14:48:14] VERBOSE[19541] app.c: [2014-02-06 14:48:14] VERBOSE[19541] config.c: [2014-02-06 14:48:15] VERBOSE[19541] app_macro.c: [2014-02-06 14:48:15] VERBOSE[19541] pbx.c: [2014-02-06 14:48:15] VERBOSE[19541] pbx.c: [2014-02-06 14:48:15] VERBOSE[19541] pbx.c: [2014-02-06 14:48:15] VERBOSE[19541] pbx.c: [2014-02-06 14:48:15] VERBOSE[19541] pbx.c: [2014-02-06 14:48:15] VERBOSE[19541] pbx.c: [2014-02-06 14:48:15] VERBOSE[19541] app_macro.c: [2014-02-06 14:48:15] VERBOSE[19541] pbx.c: [2014-02-06 14:48:15] VERBOSE[19541] sig_analog.c: [2014-02-06 14:48:15] VERBOSE[19541] chan_dahdi.c: – Starting simple switch on ‘DAHDI/1-1’
– Executing [s@from-pstn:1] ExecIf(“DAHDI/1-1”, “1?Set(__FROM_DID=s)”) in new stack
– Executing [s@from-pstn:2] Gosub(“DAHDI/1-1”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“DAHDI/1-1”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“DAHDI/1-1”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“DAHDI/1-1”, “”) in new stack
– Executing [s@from-pstn:3] Set(“DAHDI/1-1”, “CDR(did)=s”) in new stack
– Executing [s@from-pstn:4] ExecIf(“DAHDI/1-1”, “0 ?Set(CALLERID(name)=7759721077)”) in new stack
– Executing [s@from-pstn:5] Set(“DAHDI/1-1”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [s@from-pstn:6] Set(“DAHDI/1-1”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [s@from-pstn:7] Set(“DAHDI/1-1”, “__ALERT_INFO=Simple-1”) in new stack
– Executing [s@from-pstn:8] Goto(“DAHDI/1-1”, “ext-group,200,1”) in new stack
– Goto (ext-group,200,1)
– Executing [200@ext-group:1] Macro(“DAHDI/1-1”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“DAHDI/1-1”, “AMPUSER=7759721077”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“DAHDI/1-1”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“DAHDI/1-1”, “1?Set(REALCALLERIDNUM=7759721077)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“DAHDI/1-1”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“DAHDI/1-1”, “1?report”) in new stack
– Goto (macro-user-callerid,s,13)
– Executing [s@macro-user-callerid:13] GotoIf(“DAHDI/1-1”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:14] Set(“DAHDI/1-1”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:15] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,26)
– Executing [s@macro-user-callerid:26] Set(“DAHDI/1-1”, “CALLERID(number)=7759721077”) in new stack
– Executing [s@macro-user-callerid:27] Set(“DAHDI/1-1”, “CALLERID(name)=WISE, NITA”) in new stack
– Executing [s@macro-user-callerid:28] Set(“DAHDI/1-1”, “CHANNEL(language)=en”) in new stack
– Executing [200@ext-group:2] Macro(“DAHDI/1-1”, “blkvm-setifempty,”) in new stack
– Executing [s@macro-blkvm-setifempty:1] GotoIf(“DAHDI/1-1”, “1?init”) in new stack
– Goto (macro-blkvm-setifempty,s,4)
– Executing [s@macro-blkvm-setifempty:4] Set(“DAHDI/1-1”, “__BLKVM_CHANNEL=DAHDI/1-1”) in new stack
– Executing [s@macro-blkvm-setifempty:5] Set(“DAHDI/1-1”, “SHARED(BLKVM,DAHDI/1-1)=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:6] Set(“DAHDI/1-1”, “GOSUB_RETVAL=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:7] MacroExit(“DAHDI/1-1”, “”) in new stack
– Executing [200@ext-group:3] GotoIf(“DAHDI/1-1”, “1?skipov”) in new stack
– Goto (ext-group,200,6)
– Executing [200@ext-group:6] Set(“DAHDI/1-1”, “RRNODEST=”) in new stack
– Executing [200@ext-group:7] Set(“DAHDI/1-1”, “__NODEST=200”) in new stack
– Executing [200@ext-group:8] GosubIf(“DAHDI/1-1”, “0?sub-rgsetcid,s,1()”) in new stack
– Executing [200@ext-group:9] Gosub(“DAHDI/1-1”, “sub-record-check,s,1(rg,200,always)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“DAHDI/1-1”, “1?check”) in new stack
– Goto (sub-record-check,s,6)
– Executing [s@sub-record-check:6] Set(“DAHDI/1-1”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:7] GotoIf(“DAHDI/1-1”, “1?next”) in new stack
– Goto (sub-record-check,s,10)
– Executing [s@sub-record-check:10] ExecIf(“DAHDI/1-1”, “0?Return()”) in new stack
– Executing [s@sub-record-check:11] GotoIf(“DAHDI/1-1”, “0?rg,1”) in new stack
– Executing [s@sub-record-check:12] Set(“DAHDI/1-1”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“DAHDI/1-1”, “1?Set(__REC_POLICY_MODE=always)”) in new stack
– Executing [s@sub-record-check:14] Set(“DAHDI/1-1”, “NOW=1391726835”) in new stack
– Executing [s@sub-record-check:15] Set(“DAHDI/1-1”, “__DAY=06”) in new stack
– Executing [s@sub-record-check:16] Set(“DAHDI/1-1”, “__MONTH=02”) in new stack
– Executing [s@sub-record-check:17] Set(“DAHDI/1-1”, “__YEAR=2014”) in new stack
– Executing [s@sub-record-check:18] Set(“DAHDI/1-1”, “__TIMESTR=20140206-144715”) in new stack
– Executing [s@sub-record-check:19] Set(“DAHDI/1-1”, “__FROMEXTEN=7759721077”) in new stack
– Executing [s@sub-record-check:20] Set(“DAHDI/1-1”, “__CALLFILENAME=rg-200-7759721077-20140206-144715-1391726834.57”) in new stack
– Executing [s@sub-record-check:21] Goto(“DAHDI/1-1”, “rg,1”) in new stack
– Goto (sub-record-check,rg,1)
– Executing [rg@sub-record-check:1] GosubIf(“DAHDI/1-1”, “1?record,1(rg,always,7759721077)”) in new stack
– Executing [record@sub-record-check:1] Set(“DAHDI/1-1”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
– Executing [record@sub-record-check:2] MixMonitor(“DAHDI/1-1”, “2014/02/06/rg-200-7759721077-20140206-144715-1391726834.57.wav,”) in new stack
– Executing [record@sub-record-check:3] Set(“DAHDI/1-1”, “__REC_STATUS=RECORDING”) in new stack
– Executing [record@sub-record-check:4] Set(“DAHDI/1-1”, “CDR(recordingfile)=rg-200-7759721077-20140206-144715-1391726834.57.wav”) in new stack
– Executing [record@sub-record-check:5] Return(“DAHDI/1-1”, “”) in new stack
– Executing [rg@sub-record-check:2] Return(“DAHDI/1-1”, “”) in new stack
– Executing [200@ext-group:10] Set(“DAHDI/1-1”, “RingGroupMethod=ringall”) in new stack
– Executing [200@ext-group:11] Macro(“DAHDI/1-1”, “dial,20,tr,100-101-102”) in new stack
– Executing [s@macro-dial:1] GotoIf(“DAHDI/1-1”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [s@macro-dial:3] AGI(“DAHDI/1-1”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
== Begin MixMonitor Recording DAHDI/1-1
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘WISE, NITA’ number is ‘7759721077’
– AGI Script Executing Application: (SIPAddHeader) Options: (Alert-Info:Simple-1)
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 100 to extension map
– dialparties.agi: Added extension 101 to extension map
– dialparties.agi: Added extension 102 to extension map
– dialparties.agi: Extension 100 cf is disabled
– dialparties.agi: Extension 101 cf is disabled
– dialparties.agi: Extension 102 cf is disabled
– dialparties.agi: Extension 100 do not disturb is disabled
– dialparties.agi: Extension 101 do not disturb is disabled
– dialparties.agi: Extension 102 do not disturb is disabled
– dialparties.agi: dbset CALLTRACE/100 to 7759721077
– dialparties.agi: dbset CALLTRACE/101 to 7759721077
– dialparties.agi: dbset CALLTRACE/102 to 7759721077
– dialparties.agi: Filtered ARG3: 100-101-102
– <DAHDI/1-1>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“DAHDI/1-1”, “DAHDI/3&SIP/101&SIP/102,20,trM(auto-blkvm)”) in new stack
– Called DAHDI/3
app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Unknown)
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/102
– DAHDI/3-1 is ringing
– SIP/102-00000014 connected line has changed. Saving it until answer for DAHDI/1-1
– SIP/102-00000014 is ringing
– DAHDI/3-1 is ringing
– DAHDI/3-1 is ringing
– DAHDI/3-1 is ringing
– DAHDI/3-1 is ringing
– Nobody picked up in 20000 ms
– Hanging up on ‘DAHDI/3-1’
– Hungup ‘DAHDI/3-1’
– Executing [s@macro-dial:8] Set(“DAHDI/1-1”, “DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-dial:9] GosubIf(“DAHDI/1-1”, “0?NOANSWER,1”) in new stack
– Executing [200@ext-group:12] Gosub(“DAHDI/1-1”, “sub-record-cancel,s,1()”) in new stack
– Executing [s@sub-record-cancel:1] ExecIf(“DAHDI/1-1”, “0?Return()”) in new stack
– Executing [s@sub-record-cancel:2] StopMixMonitor(“DAHDI/1-1”, “”) in new stack
== MixMonitor close filestream
– Executing [s@sub-record-cancel:3] Set(“DAHDI/1-1”, “__REC_STATUS=”) in new stack
– Executing [s@sub-record-cancel:4] Set(“DAHDI/1-1”, “MON_BASE=/var/spool/asterisk/monitor/2014/02/06/”) in new stack
– Executing [s@sub-record-cancel:5] Set(“DAHDI/1-1”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-cancel:6] ExecIf(“DAHDI/1-1”, “47?System(rm -f /var/spool/asterisk/monitor/2014/02/06/rg-200-7759721077-20140206-144715-1391726834.57.wav)”) in new sta
== End MixMonitor Recording DAHDI/1-1
– Executing [s@sub-record-cancel:7] Set(“DAHDI/1-1”, “__CALLFILENAME=”) in new stack
– Executing [s@sub-record-cancel:8] Set(“DAHDI/1-1”, “CDR(recordingfile)=”) in new stack
– Executing [s@sub-record-cancel:9] Return(“DAHDI/1-1”, “”) in new stack
– Executing [200@ext-group:13] Set(“DAHDI/1-1”, “RingGroupMethod=”) in new stack
– Executing [200@ext-group:14] GotoIf(“DAHDI/1-1”, “0?nodest”) in new stack
– Executing [200@ext-group:15] Set(“DAHDI/1-1”, “__NODEST=”) in new stack
– Executing [200@ext-group:16] Macro(“DAHDI/1-1”, “blkvm-clr,”) in new stack
– Executing [s@macro-blkvm-clr:1] Set(“DAHDI/1-1”, “SHARED(BLKVM,DAHDI/1-1)=”) in new stack
– Executing [s@macro-blkvm-clr:2] Set(“DAHDI/1-1”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-clr:3] MacroExit(“DAHDI/1-1”, “”) in new stack
– Executing [200@ext-group:17] Goto(“DAHDI/1-1”, “ext-local,vmu100,1”) in new stack
– Goto (ext-local,vmu100,1)
– Executing [vmu100@ext-local:1] Macro(“DAHDI/1-1”, “vm,100,NOANSWER,”) in new stack
– Executing [s@macro-vm:1] Macro(“DAHDI/1-1”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“DAHDI/1-1”, “AMPUSER=7759721077”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“DAHDI/1-1”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“DAHDI/1-1”, “0?Set(REALCALLERIDNUM=7759721077)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“DAHDI/1-1”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“DAHDI/1-1”, “1?report”) in new stack
– Goto (macro-user-callerid,s,13)
– Executing [s@macro-user-callerid:13] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,26)
– Executing [s@macro-user-callerid:26] Set(“DAHDI/1-1”, “CALLERID(number)=7759721077”) in new stack
– Executing [s@macro-user-callerid:27] Set(“DAHDI/1-1”, “CALLERID(name)=WISE, NITA”) in new stack
– Executing [s@macro-user-callerid:28] Set(“DAHDI/1-1”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-vm:2] Set(“DAHDI/1-1”, “VMGAIN=”"") in new stack
– Executing [s@macro-vm:3] Macro(“DAHDI/1-1”, “blkvm-check,”) in new stack
– Executing [s@macro-blkvm-check:1] Set(“DAHDI/1-1”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-check:2] ExecIf(“DAHDI/1-1”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
– Executing [s@macro-blkvm-check:3] MacroExit(“DAHDI/1-1”, “”) in new stack
– Executing [s@macro-vm:4] GotoIf(“DAHDI/1-1”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] Set(“DAHDI/1-1”, “MEXTEN=100”) in new stack
– Executing [vmx@macro-vm:2] Set(“DAHDI/1-1”, “MMODE=NOANSWER”) in new stack
– Executing [vmx@macro-vm:3] Set(“DAHDI/1-1”, “RETVM=”) in new stack
– Executing [vmx@macro-vm:4] Set(“DAHDI/1-1”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:5] GotoIf(“DAHDI/1-1”, “1?chknomsg”) in new stack
– Goto (macro-vm,vmx,8)
– Executing [vmx@macro-vm:8] GotoIf(“DAHDI/1-1”, “0?s-NOANSWER,1”) in new stack
– Executing [vmx@macro-vm:9] GotoIf(“DAHDI/1-1”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,11)
– Executing [vmx@macro-vm:11] NoOp(“DAHDI/1-1”, “Checking if ext 100 is enabled: “) in new stack
– Executing [vmx@macro-vm:12] GotoIf(“DAHDI/1-1”, “1?s-NOANSWER,1”) in new stack
– Goto (macro-vm,s-NOANSWER,1)
– Executing [s-NOANSWER@macro-vm:1] Macro(“DAHDI/1-1”, “get-vmcontext,100”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“DAHDI/1-1”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“DAHDI/1-1”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“DAHDI/1-1”, “”) in new stack
– Executing [s-NOANSWER@macro-vm:2] VoiceMail(“DAHDI/1-1”, “100@default,u”””) in new stack
– <DAHDI/1-1> Playing ‘/var/spool/asterisk/voicemail/default/100/unavail.slin’ (language ‘en’)
– <DAHDI/1-1> Playing ‘vm-intro.ulaw’ (language ‘en’)
– <DAHDI/1-1> Playing ‘beep.ulaw’ (language ‘en’)
– Recording the message
– x=0, open writing: /var/spool/asterisk/voicemail/default/100/tmp/hdSwnQ format: wav, 0xa3102a8
– User hung up
== Parsing ‘/var/spool/asterisk/voicemail/default/100/INBOX/msg0000.txt’: [2014-02-06 14:48:14] VERBOSE[19541] config.c: == Found
== Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘DAHDI/1-1’ in macro ‘vm’
== Spawn extension (ext-local, vmu100, 1) exited non-zero on ‘DAHDI/1-1’
– Executing [h@ext-local:1] Macro(“DAHDI/1-1”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“DAHDI/1-1”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“DAHDI/1-1”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] Hangup(“DAHDI/1-1”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘DAHDI/1-1’ in macro ‘hangupcall’
== Spawn extension (ext-local, h, 1) exited non-zero on ‘DAHDI/1-1’
– Hanging up on ‘DAHDI/1-1’
– Hungup ‘DAHDI/1-1’

david55 · February 7, 2014, 8:36pm

This looks like a GUI dialplan. You need to take this up with its authors. If it is FreePBX, you want freepbx.org/forums/