Ring groups - phones only ring once

I had a ring group set up with ringall and it’s been working fine for months, however recently it’s started to only ring my phones once before going to voicemail.

The ring group is set up to ring two extensions (my home and work) as well as my cell phone (which is temporarily off while being repaired).
I have an inbound route pointing to the ring group, the ring group is set up with a ring time of 20 seconds (I increased to 30 but the behavior didn’t change).

Just to make sure it was a ring group issue, I changed the inbound route to dial my extension directly and it worked as expected (rang 5 times before hitting VM).

I am running FreePBX 2.3.1.1 on Trixbox
– Remote UNIX connection
�Asterisk 1.2.24 built by root @ xxx.com on a x86_64 running Linux on 2007-11-14 23:04:01 UTC

Here’s a log of such a call (phone numbers changed so as not to publish my real numbers)

faxCLI>
– Executing Set(“SIP/8045556813-00881490”, “__FROM_DID=9785557724”) in new stack
– Executing Gosub(“SIP/8045556813-00881490”, “app-blacklist-check|s|1”) in new stack
– Executing LookupBlacklist(“SIP/8045556813-00881490”, “”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “0?blacklisted”) in new stack
– Executing Return(“SIP/8045556813-00881490”, “”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “0 ?cidok”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “CALLERID(name)=9785557736”) in new stack
– Executing NoOp(“SIP/8045556813-00881490”, “CallerID is “9785557736” <9785557736>”) in new stack
– Executing Goto(“SIP/8045556813-00881490”, “ext-group|801|1”) in new stack
– Goto (ext-group,801,1)
– Executing Macro(“SIP/8045556813-00881490”, “user-callerid|”) in new stack
– Executing NoOp(“SIP/8045556813-00881490”, “user-callerid: 9785557736 9785557736”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “AMPUSER=9785557736”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “0?report”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “0?start”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “REALCALLERIDNUM=9785557736”) in new stack
– Executing NoOp(“SIP/8045556813-00881490”, “REALCALLERIDNUM is 9785557736”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “AMPUSER=”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “AMPUSERCIDNAME=”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “1?report”) in new stack
– Goto (macro-user-callerid,s,13)
– Executing NoOp(“SIP/8045556813-00881490”, "TTL: ARG1: ") in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “0?continue”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “__TTL=64”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,23)
– Executing NoOp(“SIP/8045556813-00881490”, “Using CallerID “9785557736” <9785557736>”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “1?skipdb”) in new stack
– Goto (ext-group,801,4)
– Executing Set(“SIP/8045556813-00881490”, “__NODEST=”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “__BLKVM_OVERRIDE=BLKVM/801/SIP/8045556813-00881490”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “__BLKVM_BASE=801”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “DB(BLKVM/801/SIP/8045556813-00881490)=TRUE”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “RRNODEST=”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “__NODEST=801”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “RecordMethod=Group”) in new stack
– Executing Macro(“SIP/8045556813-00881490”, “record-enable|500-550-916175550072#|Group”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “0?2:4”) in new stack
– Goto (macro-record-enable,s,4)
– Executing AGI(“SIP/8045556813-00881490”, “recordingcheck|20080108-141904|1199819944.184”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20080108-141904|1199819944.184: Recording enable for 500
recordingcheck|20080108-141904|1199819944.184: CALLFILENAME=g500-20080108-141904-1199819944.184
– AGI Script recordingcheck completed, returning 0
– Executing MixMonitor(“SIP/8045556813-00881490”, “g500-20080108-141904-1199819944.184.wav”) in new stack
– Executing Set(“SIP/8045556813-00881490”, “RingGroupMethod=ringall”) in new stack
– Executing Macro(“SIP/8045556813-00881490”, “dial|20|tw|500-550-916175550072#”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing AGI(“SIP/8045556813-00881490”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
== Begin MixMonitor Recording SIP/8045556813-00881490
dialparties.agi: Starting New Dialparties.agi
== Parsing ‘/etc/asterisk/manager.conf’: Found
== Parsing ‘/etc/asterisk/manager_custom.conf’: Found
== Manager ‘admin’ logged on from 127.0.0.1
dialparties.agi: Caller ID name is ‘9785557736’ number is '9785557736’
dialparties.agi: USE_CONFIRMATION: 'FALSE’
dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 500 to extension map
– dialparties.agi: Added extension 550 to extension map
– dialparties.agi: Added extension 916175550072# to extension map
– dialparties.agi: Extension 500 cf is disabled
– dialparties.agi: Extension 550 cf is disabled
– dialparties.agi: Extension 916175550072# cf is disabled
– dialparties.agi: Extension 500 do not disturb is disabled
– dialparties.agi: Extension 550 do not disturb is disabled
> dialparties.agi: extnum 500 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: dbset CALLTRACE/500 to 9785557736
> dialparties.agi: extnum 550 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: dbset CALLTRACE/550 to 9785557736
> dialparties.agi: extnum 916175550072# has: cw: 0; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: Built External dialstring component for 916175550072: Local/[email protected]/n
> dialparties.agi: NODEST: 801 adding M(auto-blkvm) to dialopts: twM(auto-blkvm)
> dialparties.agi: NODEST: 801 blkvm enabled macro already in dialopts: twM(auto-blkvm)
== Manager ‘admin’ logged off from 127.0.0.1
– AGI Script dialparties.agi completed, returning 0
– Executing Dial(“SIP/8045556813-00881490”, “SIP/500&SIP/550&Local/[email protected]/n|20|twM(auto-blkvm)”) in new stack
– Called 500
– Called 550
– Called [email protected]/n
– Executing Macro(“Local/[email protected],2”, “dialout-trunk|1|916175550072||”) in new stack
– Executing Set(“Local/[email protected],2”, “DIAL_TRUNK=1”) in new stack
– Executing Set(“Local/[email protected],2”, “DIAL_NUMBER=916175550072”) in new stack
– Executing Set(“Local/[email protected],2”, “ROUTE_PASSWD=”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?noauth”) in new stack
– Goto (macro-dialout-trunk,s,6)
– Executing GotoIf(“Local/[email protected],2”, “0?disabletrunk|1”) in new stack
– Executing Set(“Local/[email protected],2”, “_NODEST=”) in new stack
– Executing Set(“Local/[email protected],2”, “DIAL_TRUNK_OPTIONS=tw”) in new stack
– Executing Set(“Local/[email protected],2”, “GROUP()=OUT_1”) in new stack
– Executing Macro(“Local/[email protected],2”, “user-callerid|SKIPTTL”) in new stack
– Executing NoOp(“Local/[email protected],2”, “user-callerid: 9785557736 9785557736”) in new stack
– Executing Set(“Local/[email protected],2”, “AMPUSER=9785557736”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,13)
– Executing NoOp(“Local/[email protected],2”, “TTL: 64 ARG1: SKIPTTL”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,23)
– Executing NoOp(“Local/[email protected],2”, “Using CallerID “9785557736” <9785557736>”) in new stack
– Executing Macro(“Local/[email protected],2”, “record-enable|9785557736|OUT”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?2:4”) in new stack
– Goto (macro-record-enable,s,4)
– Executing AGI(“Local/[email protected],2”, “recordingcheck|20080108-141904|1199819944.188”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20080108-141904|1199819944.188: No AMPUSER db entry for 9785557736. Not recording
– AGI Script recordingcheck completed, returning 0
– Executing NoOp(“Local/[email protected],2”, “No recording needed”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?skipoutcid”) in new stack
– Executing Set(“Local/[email protected],2”, “DIAL_TRUNK_OPTIONS=W”) in new stack
– Executing Macro(“Local/[email protected],2”, “outbound-callerid|1”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?start”) in new stack
– Executing Set(“Local/[email protected],2”, “REALCALLERIDNUM=9785557736”) in new stack
– Executing NoOp(“Local/[email protected],2”, “REALCALLERIDNUM is 9785557736”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?normcid”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?normcid”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?normcid”) in new stack
– Executing Set(“Local/[email protected],2”, “USEROUTCID=9785557736”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?bypass:normcid”) in new stack
– Goto (macro-outbound-callerid,s,10)
– Executing Set(“Local/[email protected],2”, “EMERGENCYCID=”) in new stack
– Executing Set(“Local/[email protected],2”, “TRUNKOUTCID=8889425548”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?trunkcid”) in new stack
– Goto (macro-outbound-callerid,s,16)
– Executing GotoIf(“Local/[email protected],2”, “0?usercid”) in new stack
– Executing Set(“Local/[email protected],2”, “CALLERID(all)=8889425548”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?report”) in new stack
– Executing Set(“Local/[email protected],2”, “CALLERID(all)=9785557736”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?report:hidecid”) in new stack
– Goto (macro-outbound-callerid,s,22)
– Executing NoOp(“Local/[email protected],2”, “CallerID set to “” <9785557736>”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?nomax”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?chanfull”) in new stack
– Executing AGI(“Local/[email protected],2”, “fixlocalprefix”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
> fixlocalprefix: Using pattern 91|NXXNXXXXXX
== fixlocalprefix: Dialpattern 91|NXXNXXXXXX matched. 916175550072 -> 6175550072
– AGI Script fixlocalprefix completed, returning 0
– Executing Set(“Local/[email protected],2”, “OUTNUM=6175550072”) in new stack
– Executing Set(“Local/[email protected],2”, “custom=ZAP/g1”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?gocall”) in new stack
– Goto (macro-dialout-trunk,s,22)
– Executing Macro(“Local/[email protected],2”, “dialout-trunk-predial-hook”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?bypass|1”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “0?customtrunk”) in new stack
– Executing Dial(“Local/[email protected],2”, “ZAP/g1/6175550072|300|W”) in new stack
– Requested transfer capability: 0x00 - SPEECH
– Called g1/6175550072
– Zap/13-1 is proceeding passing it to Local/[email protected],2
– Local/[email protected],1 is proceeding passing it to SIP/8045556813-00881490
– SIP/500-00884340 is ringing
– SIP/550-008871f0 is ringing
– Zap/13-1 is making progress passing it to Local/[email protected],2
– Local/[email protected],1 is making progress passing it to SIP/8045556813-00881490
– Zap/13-1 answered Local/[email protected],2
– Local/[email protected],1 answered SIP/8045556813-00881490
– Executing Set(“Local/[email protected],1”, “__MACRO_RESULT=”) in new stack
– Executing DBdel(“Local/[email protected],1”, “BLKVM/801/SIP/8045556813-00881490”) in new stack
– DBdel: family=BLKVM, key=801/SIP/8045556813-00881490
– Hungup ‘Zap/13-1’
== Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on ‘Local/[email protected],2’ in macro ‘dialout-trunk’
== Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on ‘Local/[email protected],2’
– Executing Macro(“Local/[email protected],2”, “hangupcall”) in new stack
– Executing ResetCDR(“Local/[email protected],2”, “w”) in new stack
– Executing NoCDR(“Local/[email protected],2”, “”) in new stack
– Executing GotoIf(“Local/[email protected],2”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,6)
– Executing GotoIf(“Local/[email protected],2”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing GotoIf(“Local/[email protected],2”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,11)
– Executing Hangup(“Local/[email protected],2”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘Local/[email protected],2’ in macro ‘hangupcall’
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘Local/[email protected],2’
== Spawn extension (macro-dial, s, 10) exited non-zero on ‘SIP/8045556813-00881490’ in macro ‘dial’
== Spawn extension (macro-dial, s, 10) exited non-zero on ‘SIP/8045556813-00881490’
– Executing Macro(“SIP/8045556813-00881490”, “hangupcall”) in new stack
– Executing ResetCDR(“SIP/8045556813-00881490”, “w”) in new stack
– Executing NoCDR(“SIP/8045556813-00881490”, “”) in new stack
– Executing GotoIf(“SIP/8045556813-00881490”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,6)
– Executing GotoIf(“SIP/8045556813-00881490”, “0?skipblkvm”) in new stack
– Executing NoOp(“SIP/8045556813-00881490”, “Cleaning Up Block VM Flag: BLKVM/801/SIP/8045556813-00881490”) in new stack
– Executing DBdel(“SIP/8045556813-00881490”, “BLKVM/801/SIP/8045556813-00881490”) in new stack
– DBdel: family=BLKVM, key=801/SIP/8045556813-00881490
– DBdel: Error deleting key from database.
– Executing GotoIf(“SIP/8045556813-00881490”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,11)
– Executing Hangup(“SIP/8045556813-00881490”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘SIP/8045556813-00881490’ in macro ‘hangupcall’
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘SIP/8045556813-00881490’
== End MixMonitor Recording SIP/8045556813-00881490
fax
CLI>