The last 5-10 seconds of calls to voicemails are cut off when forwarded to email

CentOS release 5.5 (Final)
Linux 2.6.18-194.17.4.el5 on i686
Intel® Atom™ CPU D510 @ 1.66GHz, 4 cores
2 GB RAM
CPU load averages 0.02 (1 min) 0.06 (5 mins) 0.07 (15 mins)
CPU usage 6% user, 0% kernel, 0% IO, 94% idle

Local disk space
447.39 GB total, 26.85 GB used

FreePBX 2.8.0.4
Asterisk 1.6.2.13

4 Endpoints configured with 2 SIP Trunks and 2 IAX2 trunks

Settings in /etc/asterisk/vm_general.inc

maxmessage=1440 ; max length of vm message
minmessage=3 ; Minimum length of a voicemail message in seconds
maxsilence=0? 999999? ; Wait for 5 silent seconds and end the voicemail
silencethreshold=256 ; What do we consider to be silence

I have my extension x204 set for follow me to my cell and then if no answer back to system to record voicemail.

The problem is that with Follow Me setup, the last 5-10 seconds of all calls to voicemails are cut off. I used the settings above from a post I found with a similar problem.

I just discovered that it only happens when sent to email. If I leave a copy on the server, the whole message is there.

Any way to get the whole message in the email?
----------------------------------/var/log/asterisk/full--------------------------
[Nov 10 19:13:22] VERBOSE[22012] netsock.c: == Using SIP RTP TOS bits 184
[Nov 10 19:13:22] VERBOSE[22012] netsock.c: == Using SIP RTP CoS mark 5
[Nov 10 19:13:22] VERBOSE[22012] netsock.c: == Using SIP VRTP TOS bits 136
[Nov 10 19:13:22] VERBOSE[22012] netsock.c: == Using SIP VRTP CoS mark 6
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Set(“SIP/vitel-inbound-00000006”, “__FROM_DID=NNNNNNNNN”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] Gosub(“SIP/vitel-inbound-00000006”, “app-blacklist-check,s,1”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/vitel-inbound-00000006”, “0?blacklisted”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] Set(“SIP/vitel-inbound-00000006”, “CALLED_BLACKLIST=1”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] Return(“SIP/vitel-inbound-00000006”, “”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/vitel-inbound-00000006”, “0 ?Set(CALLERID(name)=NNNNNNNNN)”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:4] Set(“SIP/vitel-inbound-00000006”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:5] Set(“SIP/vitel-inbound-00000006”, “CALLERPRES()=allowed_not_screened”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:6] Goto(“SIP/vitel-inbound-00000006”, “from-did-direct,204,1”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (from-did-direct,204,1)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/vitel-inbound-00000006”, “0?ext-local,204,1”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] Macro(“SIP/vitel-inbound-00000006”, “user-callerid,”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Set(“SIP/vitel-inbound-00000006”, “AMPUSER=NNNNNNNNN”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/vitel-inbound-00000006”, “0?report”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/vitel-inbound-00000006”, “1?Set(REALCALLERIDNUM=NNNNNNNNN)”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:4] Set(“SIP/vitel-inbound-00000006”, “AMPUSER=”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:5] Set(“SIP/vitel-inbound-00000006”, “AMPUSERCIDNAME=”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]acro-user-callerid:6] GotoIf(“SIP/vitel-inbound-00000006”, “1?report”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-user-callerid,s,10)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:10] GotoIf(“SIP/vitel-inbound-00000006”, “0?continue”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:11] Set(“SIP/vitel-inbound-00000006”, “__TTL=64”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:12] GotoIf(“SIP/vitel-inbound-00000006”, “1?continue”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-user-callerid,s,19)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:19] NoOp(“SIP/vitel-inbound-00000006”, "Using CallerID “NNNNNNNNN” ") in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] Set(“SIP/vitel-inbound-00000006”, “__EXTTOCALL=204”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:4] GotoIf(“SIP/vitel-inbound-00000006”, “1?skipdb”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (from-did-direct,204,6)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:6] Set(“SIP/vitel-inbound-00000006”, “__NODEST=”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:7] Set(“SIP/vitel-inbound-00000006”, “__BLKVM_OVERRIDE=BLKVM/204/SIP/vitel-inbound-00000006”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:8] Set(“SIP/vitel-inbound-00000006”, “__BLKVM_BASE=204”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:9] Set(“SIP/vitel-inbound-00000006”, “DB(BLKVM/204/SIP/vitel-inbound-00000006)=TRUE”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:10] Set(“SIP/vitel-inbound-00000006”, “RRNODEST=”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:11] Set(“SIP/vitel-inbound-00000006”, “__NODEST=204”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:12] GosubIf(“SIP/vitel-inbound-00000006”, “0?sub-fmsetcid,s,1”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:13] Set(“SIP/vitel-inbound-00000006”, “RecordMethod=Group”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:14] Macro(“SIP/vitel-inbound-00000006”, “record-enable,1NNNNNNNNN#-204,Group”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/vitel-inbound-00000006”, “1?check”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-record-enable,s,4)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/vitel-inbound-00000006”, “0?MacroExit()”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/vitel-inbound-00000006”, “1?Group:OUT”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-record-enable,s,6)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:6] Set(“SIP/vitel-inbound-00000006”, “LOOPCNT=2”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:7] Set(“SIP/vitel-inbound-00000006”, “ITER=1”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/vitel-inbound-00000006”, “1?continue”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-record-enable,s,13)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:13] Set(“SIP/vitel-inbound-00000006”, “ITER=2”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/vitel-inbound-00000006”, “1?begin”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-record-enable,s,8)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/vitel-inbound-00000006”, “1?continue”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-record-enable,s,13)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:13] Set(“SIP/vitel-inbound-00000006”, “ITER=3”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/vitel-inbound-00000006”, “0?begin”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/vitel-inbound-00000006”, “0?IN”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/vitel-inbound-00000006”, “1?MacroExit()”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:15] Set(“SIP/vitel-inbound-00000006”, “RingGroupMethod=ringallv2”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:16] Set(“SIP/vitel-inbound-00000006”, “_FMGRP=204”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:17] GotoIf(“SIP/vitel-inbound-00000006”, “0?doconfirm”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:18] Macro(“SIP/vitel-inbound-00000006”, “dial,35,tr,1NNNNNNNNN#”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/vitel-inbound-00000006”, “1?dial”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Goto (macro-dial,s,3)
[Nov 10 19:13:22] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] AGI(“SIP/vitel-inbound-00000006”, “dialparties.agi”) in new stack
[Nov 10 19:13:22] VERBOSE[22429] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: dialparties.agi: Caller ID name is ‘NNNNNNNNN’ number is ‘NNNNNNNNN’
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: dialparties.agi: Methodology of ring is ‘ringallv2’
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: – dialparties.agi: Added extension 1NNNNNNNNN# to extension map
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: – dialparties.agi: Extension 204 cf is disabled
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: – dialparties.agi: Extension 1NNNNNNNNN# cf is disabled
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: – dialparties.agi: Extension 204 do not disturb is disabled
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: – dialparties.agi: dbset CALLTRACE/204 to NNNNNNNNN
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: – dialparties.agi: Filtered ARG3: 204-1NNNNNNNNN
[Nov 10 19:13:23] VERBOSE[22429] res_agi.c: – <SIP/vitel-inbound-00000006>AGI Script dialparties.agi completed, returning 0
[Nov 10 19:13:23] VERBOSE[22429] pbx.c: – Executing [[email protected]:7] Dial(“SIP/vitel-inbound-00000006”, “Local/[email protected]&Local/FMGL-1NNNNNNNNN#@from-internal,35,trM(auto-blkvm)”) in new stack
[Nov 10 19:13:23] VERBOSE[22429] app_dial.c: – Called [email protected]
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “In FMPR 204 with 204”) in new stack
[Nov 10 19:13:23] VERBOSE[22429] app_dial.c: – Called FMGL-1NNNNNNNNN#@from-internal
[Nov 10 19:13:23] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:1] NoOp(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “In FMGL 204 with 1NNNNNNNNN#”) in new stack
[Nov 10 19:13:23] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:2] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “0?dodnd”) in new stack
[Nov 10 19:13:23] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:3] Wait(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “RingGroupMethod=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “USE_CONFIRMATION=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “RINGGROUP_INDEX=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:5] Macro(“Local/[email protected];2”, “simple-dial,204,17”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “__EXTTOCALL=204”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “RT=17”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “CFUEXT=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “CFBEXT=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “CWI_TMP=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:6] Macro(“Local/[email protected];2”, “dial-one,17,tr,204”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DEXTEN=204”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DIALSTATUS_CW=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:3] GosubIf(“Local/[email protected];2”, “0?screen,1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:4] GosubIf(“Local/[email protected];2”, “0?cf,1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “1?skip1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Goto (macro-dial-one,s,8)
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:8] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?continue”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “EXTHASCW=ENABLED”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “0?next1:cwinusebusy”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Goto (macro-dial-one,s,23)
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:23] GotoIf(“Local/[email protected];2”, “1?next3:continue”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Goto (macro-dial-one,s,24)
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:24] ExecIf(“Local/[email protected];2”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:25] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:26] GosubIf(“Local/[email protected];2”, “1?dstring,1:dlocal,1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DSTRING=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DEVICES=1004”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “0?Set(DEVICES=004)”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “LOOPCNT=1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:6] Set(“Local/[email protected];2”, “ITER=1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “THISDIAL=SIP/1004”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:8] GosubIf(“Local/[email protected];2”, “1?zap2dahdi,1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “NEWDIAL=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “LOOPCNT2=1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “ITER2=1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “THISPART2=SIP/1004”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Set(THISPART2=DAHDI/1004)”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “NEWDIAL=SIP/1004&”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:8] Set(“Local/[email protected];2”, “ITER2=2”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?begin2”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “THISDIAL=SIP/1004”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:11] Return(“Local/[email protected];2”, “”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]al-one:9] Set(“Local/[email protected];2”, “DSTRING=SIP/1004&”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “ITER=2”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “0?begin”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:12] Set(“Local/[email protected];2”, “DSTRING=SIP/1004”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:13] Return(“Local/[email protected];2”, “”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:27] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:28] GotoIf(“Local/[email protected];2”, “1?skiptrace”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Goto (macro-dial-one,s,30)
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:30] Set(“Local/[email protected];2”, “D_OPTIONS=trM(auto-blkvm)”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:31] ExecIf(“Local/[email protected];2”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:32] ExecIf(“Local/[email protected];2”, “0?SIPAddHeader()”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:33] ExecIf(“Local/[email protected];2”, “0?SetMusicOnHold()”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:34] GosubIf(“Local/[email protected];2”, “0?qwait,1”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:35] Set(“Local/[email protected];2”, “__CWIGNORE=”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:36] Set(“Local/[email protected];2”, “__KEEPCID=TRUE”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] pbx.c: – Executing [[email protected]:37] Dial(“Local/[email protected];2”, “SIP/1004,17,trM(auto-blkvm)”) in new stack
[Nov 10 19:13:23] VERBOSE[22431] netsock.c: == Using SIP RTP TOS bits 184
[Nov 10 19:13:23] VERBOSE[22431] netsock.c: == Using SIP RTP CoS mark 5
[Nov 10 19:13:23] VERBOSE[22431] netsock.c: == Using SIP VRTP TOS bits 136
[Nov 10 19:13:23] VERBOSE[22431] netsock.c: == Using SIP VRTP CoS mark 6
[Nov 10 19:13:23] VERBOSE[22431] app_dial.c: – Called 1004
[Nov 10 19:13:23] VERBOSE[22429] app_dial.c: – Local/[email protected];1 is ringing
[Nov 10 19:13:24] VERBOSE[22431] app_dial.c: – SIP/1004-00000007 is ringing
[Nov 10 19:13:24] VERBOSE[22429] app_dial.c: – Local/[email protected];1 is ringing
[Nov 10 19:13:24] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:4] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “0?dodnd”) in new stack
[Nov 10 19:13:24] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:5] Wait(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “1”) in new stack
[Nov 10 19:13:25] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:6] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “0?dodnd”) in new stack
[Nov 10 19:13:25] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:7] Wait(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “15”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] app_dial.c: – Nobody picked up in 17000 ms
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:38] ExecIf(“Local/[email protected];2”, “0?Set(DIALSTATUS=)”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:39] GosubIf(“Local/[email protected];2”, “0?s-NOANSWER,1”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:40] MacroExit(“Local/[email protected];2”, “”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “__CWIGNORE=”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:8] Set(“Local/[email protected];2”, “PR_DIALSTATUS=NOANSWER”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:9] GosubIf(“Local/[email protected];2”, “0?docfu,1”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:10] GosubIf(“Local/[email protected];2”, “0?docfb,1”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:11] Set(“Local/[email protected];2”, “DIALSTATUS=NOANSWER”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:12] Goto(“Local/[email protected];2”, “s-NOANSWER,1”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Goto (macro-simple-dial,s-NOANSWER,1)
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “Extension is reporting s-NOANSWER”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:6] GotoIf(“Local/[email protected];2”, “1?nodnd”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Goto (from-internal,FMPR-204,8)
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:8] NoOp(“Local/[email protected];2”, “Ending FMPR 204 with 204 and dialstatus NOANSWER”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:9] Hangup(“Local/[email protected];2”, “”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: == Spawn extension (from-internal, FMPR-204, 9) exited non-zero on ‘Local/[email protected];2’
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] Macro(“Local/[email protected];2”, “hangupcall”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?skiprg”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Goto (macro-hangupcall,s,4)
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:4] GotoIf(“Local/[email protected];2”, “1?skipblkvm”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Goto (macro-hangupcall,s,7)
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “1?theend”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Goto (macro-hangupcall,s,9)
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: – Executing [[email protected]:9] Hangup(“Local/[email protected];2”, “”) in new stack
[Nov 10 19:13:40] VERBOSE[22431] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘Local/[email protected];2’ in macro ‘hangupcall’
[Nov 10 19:13:40] VERBOSE[22431] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/[email protected];2’
[Nov 10 19:13:40] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:8] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “0?dodnd”) in new stack
[Nov 10 19:13:40] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:9] DBdel(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “FM/DND/204/SIP/vitel-inbound-00000006”) in new stack
[Nov 10 19:13:40] VERBOSE[22432] app_db.c: – DBdel: family=FM, key=DND/204/SIP/vitel-inbound-00000006
[Nov 10 19:13:40] VERBOSE[22432] app_db.c: – DBdel: Error deleting key from database.
[Nov 10 19:13:40] VERBOSE[22432] pbx.c: – Executing [FMGL-1NNNNNNNNN#@from-internal:10] Macro(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “dial,18,tr,1NNNNNNNNN#”) in new stack
[Nov 10 19:13:40] VERBOSE[22432] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “1?dial”) in new stack
[Nov 10 19:13:40] VERBOSE[22432] pbx.c: – Goto (macro-dial,s,3)
[Nov 10 19:13:40] VERBOSE[22432] pbx.c: – Executing [[email protected]:3] AGI(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “dialparties.agi”) in new stack
[Nov 10 19:13:40] VERBOSE[22432] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Nov 10 19:13:41] VERBOSE[22432] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Nov 10 19:13:41] VERBOSE[22432] res_agi.c: dialparties.agi: Caller ID name is ‘NNNNNNNNN’ number is ‘NNNNNNNNN’
[Nov 10 19:13:41] VERBOSE[22432] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[Nov 10 19:13:41] VERBOSE[22432] res_agi.c: – dialparties.agi: Added extension 1NNNNNNNNN# to extension map
[Nov 10 19:13:41] VERBOSE[22432] res_agi.c: – dialparties.agi: Extension 1NNNNNNNNN# cf is disabled
[Nov 10 19:13:41] VERBOSE[22432] res_agi.c: – dialparties.agi: Filtered ARG3: 1NNNNNNNNN
[Nov 10 19:13:41] VERBOSE[22432] res_agi.c: – <Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2>AGI Script dialparties.agi completed, returning 0
[Nov 10 19:13:41] VERBOSE[22432] pbx.c: – Executing [[email protected]:7] Dial(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “Local/[email protected]/n,18,trM(auto-blkvm)”) in new stack
[Nov 10 19:13:41] VERBOSE[22432] app_dial.c: – Called [email protected]/n
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] Macro(“Local/[email protected];2”, “user-callerid,SKIPTTL,”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “AMPUSER=NNNNNNNNN”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:2] GotoIf(“Local/[email protected];2”, “1?report”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Goto (macro-user-callerid,s,10)
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:10] GotoIf(“Local/[email protected];2”, “1?continue”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Goto (macro-user-callerid,s,19)
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:19] NoOp(“Local/[email protected];2”, "Using CallerID “NNNNNNNNN” ") in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:2] NoOp(“Local/[email protected];2”, “Calling Out Route: out”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “MOHCLASS=default”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “0?Set(TRUNKCIDOVERRIDE=NNNNNNNNN)”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “_NODEST=”) in new stack
[Nov 10 19:13:41] VERBOSE[22429] app_dial.c: – Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;1 is ringing
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:6] Macro(“Local/[email protected];2”, “record-enable,NNNNNNNNN,OUT,”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?check”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Goto (macro-record-enable,s,4)
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “0?MacroExit()”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “0?Group:OUT”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Goto (macro-record-enable,s,15)
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:15] GotoIf(“Local/[email protected];2”, “0?IN”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:16] ExecIf(“Local/[email protected];2”, “1?MacroExit()”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:7] Macro(“Local/[email protected];2”, “dialout-trunk,3,NNNNNNNNN,”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DIAL_TRUNK=3”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:2] GosubIf(“Local/[email protected];2”, “0?sub-pincheck,s,1”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:3] GotoIf(“Local/[email protected];2”, “0?disabletrunk,1”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “DIAL_NUMBER=NNNNNNNNN”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:6] Set(“Local/[email protected];2”, “OUTBOUND_GROUP=OUT_3”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “0?nomax”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:8] GotoIf(“Local/[email protected];2”, “0?chanfull”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?skipoutcid”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:11] Macro(“Local/[email protected];2”, “outbound-callerid,3”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] ExecIf(“Local/[email protected];2”, “1?Set(CALLERPRES()=allowed_not_screened)”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:2] ExecIf(“Local/[email protected];2”, “1?Set(REALCALLERIDNUM=NNNNNNNNN)”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:3] GotoIf(“Local/[email protected];2”, “0?normcid”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “USEROUTCID=NNNNNNNNN”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “1?bypass”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Goto (macro-outbound-callerid,s,7)
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “EMERGENCYCID=”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:8] Set(“Local/[email protected];2”, “TRUNKOUTCID=“Mike””) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “1?trunkcid”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Goto (macro-outbound-callerid,s,12)
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:12] ExecIf(“Local/[email protected];2”, “1?Set(CALLERID(all)=Mike)”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:13] ExecIf(“Local/[email protected];2”, “1?Set(CALLERID(all)=NNNNNNNNN)”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:14] ExecIf(“Local/[email protected];2”, “0?Set(CALLERID(all)=)”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:15] ExecIf(“Local/[email protected];2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:12] GosubIf(“Local/[email protected];2”, “0?sub-flp-3,s,1”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:13] Set(“Local/[email protected];2”, “OUTNUM=NNNNNNNNN”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:14] Set(“Local/[email protected];2”, “custom=SIP/vitel-outbound”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:15] ExecIf(“Local/[email protected];2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:16] Macro(“Local/[email protected];2”, “dialout-trunk-predial-hook,”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] MacroExit(“Local/[email protected];2”, “”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:17] GotoIf(“Local/[email protected];2”, “0?bypass,1”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:18] GotoIf(“Local/[email protected];2”, “0?customtrunk”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] pbx.c: – Executing [[email protected]:19] Dial(“Local/[email protected];2”, “SIP/vitel-outbound/NNNNNNNNN,300,”) in new stack
[Nov 10 19:13:41] VERBOSE[22434] netsock.c: == Using SIP RTP TOS bits 184
[Nov 10 19:13:41] VERBOSE[22434] netsock.c: == Using SIP RTP CoS mark 5
[Nov 10 19:13:41] VERBOSE[22434] netsock.c: == Using SIP VRTP TOS bits 136
[Nov 10 19:13:41] VERBOSE[22434] netsock.c: == Using SIP VRTP CoS mark 6
[Nov 10 19:13:41] VERBOSE[22434] app_dial.c: – Called vitel-outbound/NNNNNNNNN
[Nov 10 19:13:46] VERBOSE[22434] app_dial.c: – SIP/vitel-outbound-00000008 is ringing
[Nov 10 19:13:46] VERBOSE[22434] app_dial.c: – SIP/vitel-outbound-00000008 is making progress passing it to Local/[email protected];2
[Nov 10 19:13:46] VERBOSE[22432] app_dial.c: – Local/[email protected];1 is ringing
[Nov 10 19:13:46] VERBOSE[22432] app_dial.c: – Local/[email protected];1 is making progress passing it to Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2
[Nov 10 19:13:46] VERBOSE[22429] app_dial.c: – Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;1 is ringing
[Nov 10 19:13:58] VERBOSE[22429] app_dial.c: – Nobody picked up in 35000 ms
[Nov 10 19:13:58] VERBOSE[22432] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2’ in macro ‘dial’
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: == Spawn extension (from-internal, FMGL-1NNNNNNNNN#, 10) exited non-zero on ‘Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2’
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Executing [[email protected]:1] Macro(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “hangupcall”) in new stack
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “1?skiprg”) in new stack
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Goto (macro-hangupcall,s,4)
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Executing [[email protected]:4] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “1?skipblkvm”) in new stack
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Goto (macro-hangupcall,s,7)
[Nov 10 19:13:58] VERBOSE[22434] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on ‘Local/[email protected];2’ in macro ‘dialout-trunk’
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “1?theend”) in new stack
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Goto (macro-hangupcall,s,9)
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: – Executing [[email protected]:9] Hangup(“Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2”, “”) in new stack
[Nov 10 19:13:58] VERBOSE[22432] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2’ in macro ‘hangupcall’
[Nov 10 19:13:58] VERBOSE[22432] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/FMGL-1NNNNNNNNN#@from-internal-2d3f;2’
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: == Spawn extension (from-internal, 1NNNNNNNNN, 7) exited non-zero on ‘Local/[email protected];2’
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:8] Set(“SIP/vitel-inbound-00000006”, “DIALSTATUS=NOANSWER”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] Macro(“Local/[email protected];2”, “hangupcall”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:9] GosubIf(“SIP/vitel-inbound-00000006”, “0?NOANSWER,1”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?skiprg”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:19] Goto(“SIP/vitel-inbound-00000006”, “nextstep”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Goto (macro-hangupcall,s,4)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (from-did-direct,204,21)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:21] Set(“SIP/vitel-inbound-00000006”, “RingGroupMethod=”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Executing [[email protected]:4] GotoIf(“Local/[email protected];2”, “1?skipblkvm”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:22] GotoIf(“SIP/vitel-inbound-00000006”, “0?nodest”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Goto (macro-hangupcall,s,7)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:23] Set(“SIP/vitel-inbound-00000006”, “__NODEST=”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “1?theend”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Goto (macro-hangupcall,s,9)
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: – Executing [[email protected]:9] Hangup(“Local/[email protected];2”, “”) in new stack
[Nov 10 19:13:58] VERBOSE[22434] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘Local/[email protected];2’ in macro ‘hangupcall’
[Nov 10 19:13:58] VERBOSE[22434] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/[email protected];2’
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:24] NoOp(“SIP/vitel-inbound-00000006”, “Deleting: BLKVM/204/SIP/vitel-inbound-00000006 TRUE”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:25] Goto(“SIP/vitel-inbound-00000006”, “ext-local,vmu201,1”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (ext-local,vmu201,1)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Macro(“SIP/vitel-inbound-00000006”, “vm,201,NOANSWER,”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Macro(“SIP/vitel-inbound-00000006”, “user-callerid,SKIPTTL”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Set(“SIP/vitel-inbound-00000006”, “AMPUSER=NNNNNNNNN”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/vitel-inbound-00000006”, “0?report”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/vitel-inbound-00000006”, “0?Set(REALCALLERIDNUM=NNNNNNNNN)”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:4] Set(“SIP/vitel-inbound-00000006”, “AMPUSER=”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:5] Set(“SIP/vitel-inbound-00000006”, “AMPUSERCIDNAME=”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/vitel-inbound-00000006”, “1?report”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (macro-user-callerid,s,10)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:10] GotoIf(“SIP/vitel-inbound-00000006”, “1?continue”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (macro-user-callerid,s,19)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:19] NoOp(“SIP/vitel-inbound-00000006”, “Using CallerID “NNNNNNNNN” “) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] Set(“SIP/vitel-inbound-00000006”, “VMGAIN=”””) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/vitel-inbound-00000006”, “1?vmx,1”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (macro-vm,vmx,1)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Set(“SIP/vitel-inbound-00000006”, “MEXTEN=201”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] Set(“SIP/vitel-inbound-00000006”, “MMODE=NOANSWER”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:3] Set(“SIP/vitel-inbound-00000006”, “RETVM=”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:4] Set(“SIP/vitel-inbound-00000006”, “MODE=unavail”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/vitel-inbound-00000006”, “1?chknomsg”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (macro-vm,vmx,7)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/vitel-inbound-00000006”, “0?s-NOANSWER,1”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/vitel-inbound-00000006”, “1?notdirect”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (macro-vm,vmx,10)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:10] NoOp(“SIP/vitel-inbound-00000006”, "Checking if ext 201 is enabled: ") in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/vitel-inbound-00000006”, “1?s-NOANSWER,1”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (macro-vm,s-NOANSWER,1)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Macro(“SIP/vitel-inbound-00000006”, “get-vmcontext,201”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Set(“SIP/vitel-inbound-00000006”, “VMCONTEXT=default”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/vitel-inbound-00000006”, “0?200:300”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Goto (macro-get-vmcontext,s,300)
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:300] NoOp(“SIP/vitel-inbound-00000006”, “”) in new stack
[Nov 10 19:13:58] VERBOSE[22429] pbx.c: – Executing [[email protected]:2] VoiceMail(“SIP/vitel-inbound-00000006”, “[email protected],u”) in new stack
[Nov 10 19:13:59] VERBOSE[22429] file.c: – <SIP/vitel-inbound-00000006> Playing ‘/var/spool/asterisk/voicemail/default/201/unavail.slin’ (language ‘en’)
[Nov 10 19:14:04] VERBOSE[22429] file.c: – <SIP/vitel-inbound-00000006> Playing ‘vm-intro.ulaw’ (language ‘en’)
[Nov 10 19:14:09] VERBOSE[22429] file.c: – <SIP/vitel-inbound-00000006> Playing ‘beep.ulaw’ (language ‘en’)
[Nov 10 19:14:10] VERBOSE[22429] app_voicemail.c: – Recording the message
[Nov 10 19:14:10] VERBOSE[22429] app.c: – x=0, open writing: /var/spool/asterisk/voicemail/default/201/tmp/IofeXm format: wav, 0x9e94518
[Nov 10 19:14:36] VERBOSE[22429] app.c: – User hung up
[Nov 10 19:14:36] VERBOSE[22429] config.c: == Parsing ‘/var/spool/asterisk/voicemail/default/201/INBOX/msg0000.txt’: [Nov 10 19:14:36] VERBOSE[22429] config.c: == Found
[Nov 10 19:14:36] VERBOSE[22429] app_macro.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘SIP/vitel-inbound-00000006’ in macro ‘vm’
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: == Spawn extension (ext-local, vmu201, 1) exited non-zero on ‘SIP/vitel-inbound-00000006’
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] Macro(“SIP/vitel-inbound-00000006”, “hangupcall,”) in new stack
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/vitel-inbound-00000006”, “1?skiprg”) in new stack
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Goto (macro-hangupcall,s,4)
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:4] GotoIf(“SIP/vitel-inbound-00000006”, “0?skipblkvm”) in new stack
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:5] NoOp(“SIP/vitel-inbound-00000006”, “Cleaning Up Block VM Flag: BLKVM/204/SIP/vitel-inbound-00000006”) in new stack
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:6] NoOp(“SIP/vitel-inbound-00000006”, "Deleting: BLKVM/204/SIP/vitel-inbound-00000006 ") in new stack
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/vitel-inbound-00000006”, “0?theend”) in new stack
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:8] NoOp(“SIP/vitel-inbound-00000006”, "Deleting: FM/DND/204/SIP/vitel-inbound-00000006 ") in new stack
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: – Executing [[email protected]:9] Hangup(“SIP/vitel-inbound-00000006”, “”) in new stack
[Nov 10 19:14:36] VERBOSE[22429] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/vitel-inbound-00000006’ in macro ‘hangupcall’
[Nov 10 19:14:36] VERBOSE[22429] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/vitel-inbound-00000006’