No hangup for extensions in ringgroup

martinbishop · May 7, 2010, 1:48am

I’m running Asterisk 1.6.0.17 with an ‘all phones’ ringgroup. When a call comes in, it does a ringall for three different extensions (2 cell phones and 1 landline) for 30 seconds. If no one answers, the destination is a voicemail extension. The problem I’m having is all three extensions will have a voicemail with “You have an incoming call, press 1 blah blah blah” even if the call is sent to the voicemail extension.

Changing the number of rings before voicemail picks up on the landline doesn’t help.

The problem seems to be that asterisk is not hanging up with each of the extensions after passing it off to the voicemail destination.

I’ve been searching the forums and haven’t been able to find a solution. Any help would be greatly appreciated.

Below is a log of the session with only one extension (8013463467) in the ringgroup finally giving up sending to voicemail extension 202.

== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [8018678671@from-pstn:1] Set(“SIP/atsiptrunk2-083ffb10”, “__FROM_DID=8018678671”) in new stack
– Executing [8018678671@from-pstn:2] ExecIf(“SIP/atsiptrunk2-083ffb10”, “0 ?Set(CALLERID(name)=8013283282)”) in new stack
– Executing [8018678671@from-pstn:3] Set(“SIP/atsiptrunk2-083ffb10”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [8018678671@from-pstn:4] Set(“SIP/atsiptrunk2-083ffb10”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [8018678671@from-pstn:5] Goto(“SIP/atsiptrunk2-083ffb10”, “timeconditions,1,1”) in new stack
– Goto (timeconditions,1,1)
– Executing [1@timeconditions:1] GotoIfTime(“SIP/atsiptrunk2-083ffb10”, “09:00-23:00|mon-fri||?ext-group,999,1”) in new stack
– Goto (ext-group,999,1)
– Executing [999@ext-group:1] Macro(“SIP/atsiptrunk2-083ffb10”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/atsiptrunk2-083ffb10”, “AMPUSER=8013283282”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/atsiptrunk2-083ffb10”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/atsiptrunk2-083ffb10”, “1?Set(REALCALLERIDNUM=8013283282)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/atsiptrunk2-083ffb10”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/atsiptrunk2-083ffb10”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?report”) in new stack
– Goto (macro-user-callerid,s,9)
– Executing [s@macro-user-callerid:9] GotoIf(“SIP/atsiptrunk2-083ffb10”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/atsiptrunk2-083ffb10”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:11] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [s@macro-user-callerid:18] NoOp(“SIP/atsiptrunk2-083ffb10”, “Using CallerID “Store L” <8013283282>”) in new stack
– Executing [999@ext-group:2] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?skipdb”) in new stack
– Goto (ext-group,999,4)
– Executing [999@ext-group:4] Set(“SIP/atsiptrunk2-083ffb10”, “__NODEST=”) in new stack
– Executing [999@ext-group:5] Set(“SIP/atsiptrunk2-083ffb10”, “__BLKVM_OVERRIDE=BLKVM/999/SIP/atsiptrunk2-083ffb10”) in new stack
– Executing [999@ext-group:6] Set(“SIP/atsiptrunk2-083ffb10”, “__BLKVM_BASE=999”) in new stack
– Executing [999@ext-group:7] Set(“SIP/atsiptrunk2-083ffb10”, “DB(BLKVM/999/SIP/atsiptrunk2-083ffb10)=TRUE”) in new stack
– Executing [999@ext-group:8] Set(“SIP/atsiptrunk2-083ffb10”, “RRNODEST=”) in new stack
– Executing [999@ext-group:9] Set(“SIP/atsiptrunk2-083ffb10”, “__NODEST=999”) in new stack
– Executing [999@ext-group:10] Set(“SIP/atsiptrunk2-083ffb10”, “__CWIGNORE=TRUE”) in new stack
– Executing [999@ext-group:11] Set(“SIP/atsiptrunk2-083ffb10”, “RecordMethod=Group”) in new stack
– Executing [999@ext-group:12] Macro(“SIP/atsiptrunk2-083ffb10”, “record-enable,8013463467#,Group”) in new stack
– Executing [s@macro-record-enable:1] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [s@macro-record-enable:4] AGI(“SIP/atsiptrunk2-083ffb10”, “recordingcheck,20100506-190809,1273194489.1191”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck,20100506-190809,1273194489.1191: No DB Entry AMPUSER/8013463467#/recording - Not Recording for 8013463467#, checking for others
– <SIP/atsiptrunk2-083ffb10>AGI Script recordingcheck completed, returning 0
– Executing [s@macro-record-enable:5] MacroExit(“SIP/atsiptrunk2-083ffb10”, “”) in new stack
– Executing [999@ext-group:13] Set(“SIP/atsiptrunk2-083ffb10”, “RingGroupMethod=ringall”) in new stack
– Executing [999@ext-group:14] Macro(“SIP/atsiptrunk2-083ffb10”, “dial-confirm,30,tr,8013463467#,999”) in new stack
– Executing [s@macro-dial-confirm:1] Set(“SIP/atsiptrunk2-083ffb10”, “DB(RG/999/SIP/atsiptrunk2-083ffb10)=RINGING”) in new stack
– Executing [s@macro-dial-confirm:2] Set(“SIP/atsiptrunk2-083ffb10”, “__UNIQCHAN=SIP/atsiptrunk2-083ffb10”) in new stack
– Executing [s@macro-dial-confirm:3] Set(“SIP/atsiptrunk2-083ffb10”, “USE_CONFIRMATION=TRUE”) in new stack
– Executing [s@macro-dial-confirm:4] Set(“SIP/atsiptrunk2-083ffb10”, “RINGGROUP_INDEX=999”) in new stack
– Executing [s@macro-dial-confirm:5] Set(“SIP/atsiptrunk2-083ffb10”, “ARG4=”) in new stack
– Executing [s@macro-dial-confirm:6] Macro(“SIP/atsiptrunk2-083ffb10”, “dial,30,tr,8013463467#”) in new stack
– Executing [s@macro-dial:1] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [s@macro-dial:3] AGI(“SIP/atsiptrunk2-083ffb10”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘Store L’ number is ‘8013283282’
> dialparties.agi: USE_CONFIRMATION: ‘TRUE’
> dialparties.agi: RINGGROUP_INDEX: '999’
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 8013463467# to extension map
– dialparties.agi: Extension 8013463467# cf is disabled
> dialparties.agi: extnum 8013463467# has: cw: 0; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: Built External dialstring component for 8013463467: Local/RG-999-8013463467#@from-internal
– dialparties.agi: Filtered ARG3: 8013463467
– <SIP/atsiptrunk2-083ffb10>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“SIP/atsiptrunk2-083ffb10”, “Local/RG-999-8013463467#@from-internal,30,tr”) in new stack
– Executing [RG-999-8013463467#@from-internal:1] Macro(“Local/RG-999-8013463467#@from-internal-9653;2”, “dial,30,M(confirm^^^999)tr,8013463467#”) in new stack
– Executing [s@macro-dial:1] GotoIf(“Local/RG-999-8013463467#@from-internal-9653;2”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [s@macro-dial:3] AGI(“Local/RG-999-8013463467#@from-internal-9653;2”, “dialparties.agi”) in new stack
– Called RG-999-8013463467#@from-internal
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘Store L’ number is ‘8013283282’
> dialparties.agi: USE_CONFIRMATION: ‘FALSE’
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘none’
– dialparties.agi: Added extension 8013463467# to extension map
> dialparties.agi: Extension 8013463467# has call screening off
– dialparties.agi: Extension 8013463467# cf is disabled
> dialparties.agi: extnum 8013463467# has: cw: 0; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: Built External dialstring component for 8013463467: Local/8013463467@from-internal/n
– dialparties.agi: Filtered ARG3: 8013463467
> dialparties.agi: NODEST: 999 blkvm enabled macro already in dialopts: M(confirm^^^999)tr
> dialparties.agi: NODEST: 999 blkvm enabled macro already in dialopts: M(confirm^^^999)tr
– <Local/RG-999-8013463467#@from-internal-9653;2>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“Local/RG-999-8013463467#@from-internal-9653;2”, “Local/8013463467@from-internal/n,30,M(confirm^^^999)tr”) in new stack
– Executing [8013463467@from-internal:1] Macro(“Local/8013463467@from-internal-a5cc;2”, “user-callerid,SKIPTTL,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“Local/8013463467@from-internal-a5cc;2”, “AMPUSER=8013283282”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,9)
– Executing [s@macro-user-callerid:9] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [s@macro-user-callerid:18] NoOp(“Local/8013463467@from-internal-a5cc;2”, “Using CallerID “Store L” <8013283282>”) in new stack
– Executing [8013463467@from-internal:2] Set(“Local/8013463467@from-internal-a5cc;2”, “_NODEST=”) in new stack
– Executing [8013463467@from-internal:3] Macro(“Local/8013463467@from-internal-a5cc;2”, “record-enable,8013283282,OUT,”) in new stack
– Executing [s@macro-record-enable:1] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [s@macro-record-enable:4] AGI(“Local/8013463467@from-internal-a5cc;2”, “recordingcheck,20100506-190810,1273194490.1195”) in new stack
– Called 8013463467@from-internal/n
– Local/RG-999-8013463467#@from-internal-9653;1 is ringing
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck,20100506-190810,1273194490.1195: No AMPUSER db entry for 8013283282. Not recording
– <Local/8013463467@from-internal-a5cc;2>AGI Script recordingcheck completed, returning 0
– Executing [s@macro-record-enable:5] MacroExit(“Local/8013463467@from-internal-a5cc;2”, “”) in new stack
– Executing [8013463467@from-internal:4] Macro(“Local/8013463467@from-internal-a5cc;2”, “dialout-trunk,2,8013463467,”) in new stack
– Executing [s@macro-dialout-trunk:1] Set(“Local/8013463467@from-internal-a5cc;2”, “DIAL_TRUNK=2”) in new stack
– Executing [s@macro-dialout-trunk:2] GosubIf(“Local/8013463467@from-internal-a5cc;2”, “0?sub-pincheck,s,1”) in new stack
– Executing [s@macro-dialout-trunk:3] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “0?disabletrunk,1”) in new stack
– Executing [s@macro-dialout-trunk:4] Set(“Local/8013463467@from-internal-a5cc;2”, “DIAL_NUMBER=8013463467”) in new stack
– Executing [s@macro-dialout-trunk:5] Set(“Local/8013463467@from-internal-a5cc;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
– Executing [s@macro-dialout-trunk:6] Set(“Local/8013463467@from-internal-a5cc;2”, “OUTBOUND_GROUP=OUT_2”) in new stack
– Executing [s@macro-dialout-trunk:7] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?nomax”) in new stack
– Goto (macro-dialout-trunk,s,9)
– Executing [s@macro-dialout-trunk:9] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “0?skipoutcid”) in new stack
– Executing [s@macro-dialout-trunk:10] Set(“Local/8013463467@from-internal-a5cc;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
– Executing [s@macro-dialout-trunk:11] Macro(“Local/8013463467@from-internal-a5cc;2”, “outbound-callerid,2”) in new stack
– Executing [s@macro-outbound-callerid:1] ExecIf(“Local/8013463467@from-internal-a5cc;2”, “1?Set(CALLERPRES()=allowed_not_screened)”) in new stack
– Executing [s@macro-outbound-callerid:2] ExecIf(“Local/8013463467@from-internal-a5cc;2”, “1?Set(REALCALLERIDNUM=8013283282)”) in new stack
– Executing [s@macro-outbound-callerid:3] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “0?normcid”) in new stack
– Executing [s@macro-outbound-callerid:4] Set(“Local/8013463467@from-internal-a5cc;2”, “USEROUTCID=8013283282”) in new stack
– Executing [s@macro-outbound-callerid:5] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?bypass”) in new stack
– Goto (macro-outbound-callerid,s,7)
– Executing [s@macro-outbound-callerid:7] Set(“Local/8013463467@from-internal-a5cc;2”, “EMERGENCYCID=”) in new stack
– Executing [s@macro-outbound-callerid:8] Set(“Local/8013463467@from-internal-a5cc;2”, “TRUNKOUTCID=8018678671”) in new stack
– Executing [s@macro-outbound-callerid:9] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?trunkcid”) in new stack
– Goto (macro-outbound-callerid,s,12)
– Executing [s@macro-outbound-callerid:12] ExecIf(“Local/8013463467@from-internal-a5cc;2”, “1?Set(CALLERID(all)=8018678671)”) in new stack
– Executing [s@macro-outbound-callerid:13] ExecIf(“Local/8013463467@from-internal-a5cc;2”, “1?Set(CALLERID(all)=8013283282)”) in new stack
– Executing [s@macro-outbound-callerid:14] ExecIf(“Local/8013463467@from-internal-a5cc;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
– Executing [s@macro-dialout-trunk:12] ExecIf(“Local/8013463467@from-internal-a5cc;2”, “1?AGI(fixlocalprefix)”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
> fixlocalprefix: Using pattern 801+NXXXXXX
> fixlocalprefix: Using pattern 1|NXXNXXXXXX
– <Local/8013463467@from-internal-a5cc;2>AGI Script fixlocalprefix completed, returning 0
– Executing [s@macro-dialout-trunk:13] Set(“Local/8013463467@from-internal-a5cc;2”, “OUTNUM=8013463467”) in new stack
– Executing [s@macro-dialout-trunk:14] Set(“Local/8013463467@from-internal-a5cc;2”, “custom=SIP/atsiptrunk1”) in new stack
– Executing [s@macro-dialout-trunk:15] ExecIf(“Local/8013463467@from-internal-a5cc;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^))”) in new stack
– Executing [s@macro-dialout-trunk:16] Macro(“Local/8013463467@from-internal-a5cc;2”, “dialout-trunk-predial-hook,”) in new stack
– Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/8013463467@from-internal-a5cc;2”, “”) in new stack
– Executing [s@macro-dialout-trunk:17] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “0?bypass,1”) in new stack
– Executing [s@macro-dialout-trunk:18] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “0?customtrunk”) in new stack
– Executing [s@macro-dialout-trunk:19] Dial(“Local/8013463467@from-internal-a5cc;2”, “SIP/atsiptrunk1/8013463467,300,”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called atsiptrunk1/8013463467
– SIP/atsiptrunk1-0846fac0 is making progress passing it to Local/8013463467@from-internal-a5cc;2
– Local/8013463467@from-internal-a5cc;1 is making progress passing it to Local/RG-999-8013463467#@from-internal-9653;2
– SIP/atsiptrunk1-0846fac0 is ringing
– SIP/atsiptrunk1-0846fac0 is making progress passing it to Local/8013463467@from-internal-a5cc;2
– Local/8013463467@from-internal-a5cc;1 is ringing
– Local/8013463467@from-internal-a5cc;1 is making progress passing it to Local/RG-999-8013463467#@from-internal-9653;2
– Local/RG-999-8013463467#@from-internal-9653;1 is ringing
– SIP/atsiptrunk1-0846fac0 answered Local/8013463467@from-internal-a5cc;2
– Local/8013463467@from-internal-a5cc;1 answered Local/RG-999-8013463467#@from-internal-9653;2
– Executing [s@macro-confirm:1] Set(“Local/8013463467@from-internal-a5cc;1”, “LOOPCOUNT=0”) in new stack
– Executing [s@macro-confirm:2] Set(“Local/8013463467@from-internal-a5cc;1”, “__MACRO_RESULT=ABORT”) in new stack
– Executing [s@macro-confirm:3] Set(“Local/8013463467@from-internal-a5cc;1”, “MSG1=“incoming-call-1-accept-2-decline””) in new stack
– Executing [s@macro-confirm:4] BackGround(“Local/8013463467@from-internal-a5cc;1”, “incoming-call-1-accept-2-decline,m,en,macro-confirm”) in new stack
– <Local/8013463467@from-internal-a5cc;1> Playing ‘incoming-call-1-accept-2-decline.slin’ (language ‘en’)
– Executing [s@macro-confirm:5] Read(“Local/8013463467@from-internal-a5cc;1”, “INPUT,1,4”) in new stack
– Accepting a maximum of 1 digits.
– User entered nothing.
– Executing [s@macro-confirm:6] GotoIf(“Local/8013463467@from-internal-a5cc;1”, “0?,1:t,1”) in new stack
– Goto (macro-confirm,t,1)
– Executing [t@macro-confirm:1] GotoIf(“Local/8013463467@from-internal-a5cc;1”, “0?toolate,1”) in new stack
– Executing [t@macro-confirm:2] Set(“Local/8013463467@from-internal-a5cc;1”, “LOOPCOUNT=1”) in new stack
– Executing [t@macro-confirm:3] GotoIf(“Local/8013463467@from-internal-a5cc;1”, “1?s,start:noanswer,1”) in new stack
– Goto (macro-confirm,s,4)
– Executing [s@macro-confirm:4] BackGround(“Local/8013463467@from-internal-a5cc;1”, “incoming-call-1-accept-2-decline,m,en,macro-confirm”) in new stack
– <Local/8013463467@from-internal-a5cc;1> Playing ‘incoming-call-1-accept-2-decline.slin’ (language ‘en’)
– Executing [s@macro-confirm:5] Read(“Local/8013463467@from-internal-a5cc;1”, “INPUT,1,4”) in new stack
– Accepting a maximum of 1 digits.
– Nobody picked up in 30000 ms
– Executing [s@macro-dial:8] Set(“SIP/atsiptrunk2-083ffb10”, “DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-dial:9] GosubIf(“SIP/atsiptrunk2-083ffb10”, “0?NOANSWER,1”) in new stack
– Executing [s@macro-dial-confirm:7] DBdel(“SIP/atsiptrunk2-083ffb10”, “RG/999/SIP/atsiptrunk2-083ffb10”) in new stack
– DBdel: family=RG, key=999/SIP/atsiptrunk2-083ffb10
– Executing [s@macro-dial-confirm:8] Set(“SIP/atsiptrunk2-083ffb10”, “USE_CONFIRMATION=”) in new stack
– Executing [s@macro-dial-confirm:9] Set(“SIP/atsiptrunk2-083ffb10”, “RINGGROUP_INDEX=”) in new stack
– Executing [999@ext-group:15] Set(“SIP/atsiptrunk2-083ffb10”, “RingGroupMethod=”) in new stack
– Executing [999@ext-group:16] GotoIf(“SIP/atsiptrunk2-083ffb10”, “0?nodest”) in new stack
– Executing [999@ext-group:17] Set(“SIP/atsiptrunk2-083ffb10”, “__CWIGNORE=”) in new stack
– Executing [999@ext-group:18] Set(“SIP/atsiptrunk2-083ffb10”, “__NODEST=”) in new stack
– Executing [999@ext-group:19] DBdel(“SIP/atsiptrunk2-083ffb10”, “BLKVM/999/SIP/atsiptrunk2-083ffb10”) in new stack
– DBdel: family=BLKVM, key=999/SIP/atsiptrunk2-083ffb10
– Executing [999@ext-group:20] Goto(“SIP/atsiptrunk2-083ffb10”, “ext-local,vmu202,1”) in new stack
– Goto (ext-local,vmu202,1)
– Executing [vmu202@ext-local:1] Macro(“SIP/atsiptrunk2-083ffb10”, “vm,202,NOANSWER,”) in new stack
– Executing [s@macro-vm:1] Macro(“SIP/atsiptrunk2-083ffb10”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/atsiptrunk2-083ffb10”, “AMPUSER=8013283282”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/atsiptrunk2-083ffb10”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/atsiptrunk2-083ffb10”, “0?Set(REALCALLERIDNUM=8013283282)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/atsiptrunk2-083ffb10”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/atsiptrunk2-083ffb10”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?report”) in new stack
– Goto (macro-user-callerid,s,9)
– Executing [s@macro-user-callerid:9] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [s@macro-user-callerid:18] NoOp(“SIP/atsiptrunk2-083ffb10”, “Using CallerID “Store L” <8013283282>”) in new stack
– Executing [s@macro-vm:2] Set(“SIP/atsiptrunk2-083ffb10”, “VMGAIN=”"") in new stack
– Executing [s@macro-vm:3] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] GotoIf(“SIP/atsiptrunk2-083ffb10”, “0?s-NOANSWER,1”) in new stack
– Executing [vmx@macro-vm:2] Set(“SIP/atsiptrunk2-083ffb10”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:3] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,5)
– Executing [vmx@macro-vm:5] NoOp(“SIP/atsiptrunk2-083ffb10”, "Checking if ext 202 is enabled: ") in new stack
– Executing [vmx@macro-vm:6] GotoIf(“SIP/atsiptrunk2-083ffb10”, “1?s-NOANSWER,1”) in new stack
– Goto (macro-vm,s-NOANSWER,1)
– Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/atsiptrunk2-083ffb10”, “get-vmcontext,202”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“SIP/atsiptrunk2-083ffb10”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/atsiptrunk2-083ffb10”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“SIP/atsiptrunk2-083ffb10”, “”) in new stack
– Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/atsiptrunk2-083ffb10”, “202@default,su”) in new stack
– <SIP/atsiptrunk2-083ffb10> Playing ‘/var/spool/asterisk/voicemail/default/202/unavail.slin’ (language ‘en’)
– User entered nothing.
– Executing [s@macro-confirm:6] GotoIf(“Local/8013463467@from-internal-a5cc;1”, “0?,1:t,1”) in new stack
– Goto (macro-confirm,t,1)
– Executing [t@macro-confirm:1] GotoIf(“Local/8013463467@from-internal-a5cc;1”, “1?toolate,1”) in new stack
– Goto (macro-confirm,toolate,1)
– Executing [toolate@macro-confirm:1] Set(“Local/8013463467@from-internal-a5cc;1”, “MSG2=“incoming-call-no-longer-avail””) in new stack
– Executing [toolate@macro-confirm:2] Playback(“Local/8013463467@from-internal-a5cc;1”, “incoming-call-no-longer-avail”) in new stack
– <Local/8013463467@from-internal-a5cc;1> Playing ‘incoming-call-no-longer-avail.slin’ (language ‘en’)
== Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘SIP/atsiptrunk2-083ffb10’ in macro ‘vm’
== Spawn extension (ext-local, vmu202, 1) exited non-zero on ‘SIP/atsiptrunk2-083ffb10’
– Executing [toolate@macro-confirm:3] Set(“Local/8013463467@from-internal-a5cc;1”, “__MACRO_RESULT=ABORT”) in new stack
– Executing [toolate@macro-confirm:4] MacroExit(“Local/8013463467@from-internal-a5cc;1”, “”) in new stack
– Executing [h@macro-dialout-trunk:1] Macro(“Local/8013463467@from-internal-a5cc;2”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [s@macro-hangupcall:4] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [s@macro-hangupcall:7] GotoIf(“Local/8013463467@from-internal-a5cc;2”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] Hangup(“Local/8013463467@from-internal-a5cc;2”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘Local/8013463467@from-internal-a5cc;2’ in macro ‘hangupcall’
== Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘Local/8013463467@from-internal-a5cc;2’
== Spawn extension (macro-dial, s, 7) exited non-zero on ‘Local/RG-999-8013463467#@from-internal-9653;2’ in macro ‘dial’
== Spawn extension (from-internal, RG-999-8013463467#, 1) exited non-zero on ‘Local/RG-999-8013463467#@from-internal-9653;2’
– Executing [h@from-internal:1] Macro(“Local/RG-999-8013463467#@from-internal-9653;2”, “hangupcall”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“Local/RG-999-8013463467#@from-internal-9653;2”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [s@macro-hangupcall:4] GotoIf(“Local/RG-999-8013463467#@from-internal-9653;2”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [s@macro-hangupcall:7] GotoIf(“Local/RG-999-8013463467#@from-internal-9653;2”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] Hangup(“Local/RG-999-8013463467#@from-internal-9653;2”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘Local/RG-999-8013463467#@from-internal-9653;2’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/RG-999-8013463467#@from-internal-9653;2’
== Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on ‘Local/8013463467@from-internal-a5cc;2’ in macro ‘dialout-trunk’
== Spawn extension (from-internal, 8013463467, 4) exited non-zero on ‘Local/8013463467@from-internal-a5cc;2’

vespaman · May 9, 2010, 2:12am

Have you selected this option “confirm calls” for your ring group ?

martinbishop · May 9, 2010, 3:18am

Yes, I did select confirm calls for the ring group. That’s the recording on the voicemail to the landline and cell phones. “You have an incoming call, press 1 to accept, 2 to decline…”

Any other thoughts vespaman?

vespaman · May 10, 2010, 5:41am

what happens when you unselect confirm calls martinbishop ? It may be a timing thing.

martinbishop · May 10, 2010, 4:09pm

I really need confirm calls because it’s a shared personal/business cell phone. I’ll try disabling confirm calls and report back what I find.

martinbishop · May 11, 2010, 1:14am

After disabling confirm calls, it went to voicemail on my cell instead of routing to the voicemail extension 202.

Does that confirm your timing issues thought?

w5waf · May 11, 2010, 2:00am

I’d always have confirm calls enabled when forwarding to an outside line with voicemail.
getting it to work transparently requires patience and trial and error. You’ll have to play with the timing and by trial and error get something that works MOST of the time. Right now we have the initial ring time set at 0 and the max ring time @ 30.

We’ve been fooling with this function for nearly 4 years and still have the occasional hiccup. Once you get it where the operation is acceptable, you’ll find its something you can’t do without.

Bill

martinbishop · May 11, 2010, 3:40am

Would be nice to get this working. Thanks for the tip Bill. I can’t find initial ring time settings… only one called Ring Time, which I have set to 30 seconds.

I’m not even sure the best way to debug this. Should I be looking in extensions_additional.conf?

plindheimer · May 11, 2010, 2:45pm

The trace above looks like a single external number in your ringgroup.

In any event, you are going to end up with messages in the far end voicemail, there is little that can be done there. If the far end answers the call it will play the message.

One thing you could do that may make make the hangup more rapid is to change the default “Too Late Announce” message to something shorter, since upon detecting that the call was answered by anyone, once it loops through it’s loop, it will play that message before hanging up. You can also change the “Remote Announce” message to something much shorter, the combined effect will make an overall shorter looping sequence which may help.

Once the call is answered somewhere though, this should finish its sequence and hangup, it’s simply a matter of if the remote phone’s have stopped ringing at that point and been picked up by their voicemail systems.

If you find that putting the ringgroup at an extremely short time (say 10 seconds or so) is still resulting in the messages being picked up, then maybe there isn’t something right, or maybe there is something more that we can do to try and detect the issue sooner and hangup quicker.

w5waf · May 11, 2010, 11:50am

Sorry, I was thinking of the confirm calls in “follow me” which has an initial ring time. In ring groups, this is always 0.

We’ve also been in touch with our cell provider and had them lengthen the delay before it goes to voicemail. There may be a similar setting for your PSTN line.

THe situation is that there is reall no “prboblem” or “bug”. You’re dealing with 3 different phone systems (FreePBX/asterisk, cell, PSTN). There’s no magic bullet. It’s going to be a lot of trial and error to make them play nice with each other.

Bill

martinbishop · May 11, 2010, 5:35pm

Thanks for the suggestion about shortening the ‘too late announce’ and ‘remote announce’. Great idea, I’ll try that and see if it helps. I took the additional numbers out of the ring group above to try and simplify the log.

I’ll report back my findings on the shorter announcements. Thanks again.

martinbishop · May 14, 2010, 6:53pm

Philippe,

I shortened the announcement to 1 second (‘press 1’) and it seems to have worked. Thank you for help!