Follow-me timer not counting as required

Hi all,
First, I’m French so please excuse me if my english is sometimes bad :slight_smile:

I have a running install of FPBX V.2.8.0.2 & Asterisk 1.4.27.1 with trunks registred and working.
There is 10 extensions all working if I deal with Inbound routes and external calls are well.

But ! When I’m trying to set up a follow-me, I have a problem with predialing counter :

I want all my trunks to ring on one ext (101)
I want a behaviour which allow to ring all other ext in case of 101 is not answering in 25 sec.

I used follow-me on 101:

  • Initial ring time : 25
  • Ring Strategy : ringallv2
  • Ring Time : 60
  • Follow-me list : ext 999 for test (it exists and works)
  • Dest If no answer : put on hold forever

My problem is once the 25 sec are elapsed it goes directly on Dest if no answer.
If I set a Initial ring time at 15, once the 15 sec are elapsed the others ext ring only for 10 sec only (they might ring 60 ? no ?) and then go on hold forever.
I tried to put the initial extension (101) in the follow-me group but didn’t notice a change …

It make me think the couter is locked at 25 sec which is a big probleme for the dialpplan I whant to use.

I tried to make the same thing whith Ring groups & I encounter exactly the same behaviour.

By the way (It may be important), I upgrade from FPBX 2.6.0.2 to present version by following the steps in the module admin.

Is there something I’m missing ?

Hope someone could help me in my quest of the lost follow-me counter.
I Posted the CLI log with all extension enabled :

-----------BEGIN OF LOG------------
brain-ipbx*CLI>
– Executing [[email protected]:1] Goto(“SIP/OVH-CNIM-00000094”, “from-trunk|X|1”) in new stack
– Goto (from-trunk,X,1)
– Executing [[email protected]:1] Set(“SIP/OVH-CNIM-00000094”, “__FROM_DID=X”) in new stack
– Executing [[email protected]:2] Set(“SIP/OVH-CNIM-00000094”, “CHANNEL(language)=fr”) in new stack
– Executing [[email protected]:3] Gosub(“SIP/OVH-CNIM-00000094”, “app-blacklist-check|s|1”) in new stack
– Executing [[email protected]:1] LookupBlacklist(“SIP/OVH-CNIM-00000094”, “”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/OVH-CNIM-00000094”, “0?blacklisted”) in new stack
– Executing [[email protected]:3] Set(“SIP/OVH-CNIM-00000094”, “CALLED_BLACKLIST=1”) in new stack
– Executing [[email protected]:4] Return(“SIP/OVH-CNIM-00000094”, “”) in new stack
– Executing [[email protected]:4] ExecIf(“SIP/OVH-CNIM-00000094”, “0 |Set|CALLERID(name)=anonymous”) in new stack
– Executing [[email protected]:5] Set(“SIP/OVH-CNIM-00000094”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [[email protected]:6] SetCallerPres(“SIP/OVH-CNIM-00000094”, “allowed_not_screened”) in new stack
– Executing [[email protected]:7] Goto(“SIP/OVH-CNIM-00000094”, “from-did-direct|101|1”) in new stack
– Goto (from-did-direct,101,1)
– Executing [[email protected]:1] GotoIf(“SIP/OVH-CNIM-00000094”, “0?ext-local|101|1”) in new stack
– Executing [[email protected]:2] Macro(“SIP/OVH-CNIM-00000094”, “user-callerid|”) in new stack
– Executing [[email protected]:1] Set(“SIP/OVH-CNIM-00000094”, “AMPUSER=anonymous”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/OVH-CNIM-00000094”, “0?report”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/OVH-CNIM-00000094”, “1|Set|REALCALLERIDNUM=anonymous”) in new stack
– Executing [[email protected]:4] Set(“SIP/OVH-CNIM-00000094”, “AMPUSER=”) in new stack
– Executing [[email protected]:5] Set(“SIP/OVH-CNIM-00000094”, “AMPUSERCIDNAME=”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/OVH-CNIM-00000094”, “1?report”) in new stack
– Goto (macro-user-callerid,s,10)
– Executing [[email protected]:10] GotoIf(“SIP/OVH-CNIM-00000094”, “0?continue”) in new stack
– Executing [[email protected]:11] Set(“SIP/OVH-CNIM-00000094”, “__TTL=64”) in new stack
– Executing [[email protected]:12] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,19)
– Executing [[email protected]:19] NoOp(“SIP/OVH-CNIM-00000094”, "Using CallerID “Anonymous” ") in new stack
– Executing [[email protected]:3] Set(“SIP/OVH-CNIM-00000094”, “__EXTTOCALL=101”) in new stack
– Executing [[email protected]:4] GotoIf(“SIP/OVH-CNIM-00000094”, “1?skipdb”) in new stack
– Goto (from-did-direct,101,6)
– Executing [[email protected]:6] Set(“SIP/OVH-CNIM-00000094”, “__NODEST=”) in new stack
– Executing [[email protected]:7] Set(“SIP/OVH-CNIM-00000094”, “__BLKVM_OVERRIDE=BLKVM/101/SIP/OVH-CNIM-00000094”) in new stack
– Executing [[email protected]:8] Set(“SIP/OVH-CNIM-00000094”, “__BLKVM_BASE=101”) in new stack
– Executing [[email protected]:9] Set(“SIP/OVH-CNIM-00000094”, “DB(BLKVM/101/SIP/OVH-CNIM-00000094)=TRUE”) in new stack
– Executing [[email protected]:10] Set(“SIP/OVH-CNIM-00000094”, “RRNODEST=”) in new stack
– Executing [[email protected]:11] Set(“SIP/OVH-CNIM-00000094”, “__NODEST=101”) in new stack
– Executing [[email protected]:12] GosubIf(“SIP/OVH-CNIM-00000094”, “0?sub-fmsetcid|s|1”) in new stack
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “RecordMethod=Group”) in new stack
– Executing [[email protected]:14] Macro(“SIP/OVH-CNIM-00000094”, “record-enable|101-102-103-104-105-106-101|Group”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/OVH-CNIM-00000094”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [[email protected]:4] ExecIf(“SIP/OVH-CNIM-00000094”, “0|MacroExit|”) in new stack
– Executing [[email protected]:5] GotoIf(“SIP/OVH-CNIM-00000094”, “1?Group:OUT”) in new stack
– Goto (macro-record-enable,s,6)
– Executing [[email protected]:6] Set(“SIP/OVH-CNIM-00000094”, “LOOPCNT=7”) in new stack
– Executing [[email protected]:7] Set(“SIP/OVH-CNIM-00000094”, “ITER=1”) in new stack
– Executing [[email protected]:8] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-record-enable,s,13)
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “ITER=2”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/OVH-CNIM-00000094”, “1?begin”) in new stack
– Goto (macro-record-enable,s,8)
– Executing [[email protected]:8] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-record-enable,s,13)
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “ITER=3”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/OVH-CNIM-00000094”, “1?begin”) in new stack
– Goto (macro-record-enable,s,8)
– Executing [[email protected]:8] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-record-enable,s,13)
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “ITER=4”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/OVH-CNIM-00000094”, “1?begin”) in new stack
– Goto (macro-record-enable,s,8)
– Executing [[email protected]:8] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-record-enable,s,13)
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “ITER=5”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/OVH-CNIM-00000094”, “1?begin”) in new stack
– Goto (macro-record-enable,s,8)
– Executing [[email protected]:8] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-record-enable,s,13)
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “ITER=6”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/OVH-CNIM-00000094”, “1?begin”) in new stack
– Goto (macro-record-enable,s,8)
– Executing [[email protected]:8] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-record-enable,s,13)
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “ITER=7”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/OVH-CNIM-00000094”, “1?begin”) in new stack
– Goto (macro-record-enable,s,8)
– Executing [[email protected]:8] GotoIf(“SIP/OVH-CNIM-00000094”, “1?continue”) in new stack
– Goto (macro-record-enable,s,13)
– Executing [[email protected]:13] Set(“SIP/OVH-CNIM-00000094”, “ITER=8”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/OVH-CNIM-00000094”, “0?begin”) in new stack
– Executing [[email protected]:15] GotoIf(“SIP/OVH-CNIM-00000094”, “0?IN”) in new stack
– Executing [[email protected]:16] ExecIf(“SIP/OVH-CNIM-00000094”, “1|MacroExit|”) in new stack
– Executing [[email protected]:15] Set(“SIP/OVH-CNIM-00000094”, “RingGroupMethod=ringallv2”) in new stack
– Executing [[email protected]:16] Set(“SIP/OVH-CNIM-00000094”, “_FMGRP=101”) in new stack
– Executing [[email protected]:17] GotoIf(“SIP/OVH-CNIM-00000094”, “0?doconfirm”) in new stack
– Executing [[email protected]:18] Macro(“SIP/OVH-CNIM-00000094”, “dial|85|tr|101-102-103-104-105-106”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/OVH-CNIM-00000094”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [[email protected]:3] AGI(“SIP/OVH-CNIM-00000094”, “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 ‘Anonymous’ number is 'anonymous’
dialparties.agi: USE_CONFIRMATION: 'FALSE’
dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘ringallv2’
– dialparties.agi: Added extension 101 to extension map
– dialparties.agi: Added extension 102 to extension map
– dialparties.agi: Added extension 103 to extension map
– dialparties.agi: Added extension 104 to extension map
– dialparties.agi: Added extension 105 to extension map
– dialparties.agi: Added extension 106 to extension map
> dialparties.agi: got fmgrp_prering: 25, fmgrp_grptime: 60
> dialparties.agi: fmgrp_totalprering: 85
> dialparties.agi: found extension in pre-ring and array
> dialparties.agi: ringallv2 ring times: REALPRERING: 85, PRERING: 25
> dialparties.agi: Extension 106 has call screening off
– dialparties.agi: Extension 101 cf is disabled
– dialparties.agi: Extension 102 cf is disabled
– dialparties.agi: Extension 103 cf is disabled
– dialparties.agi: Extension 104 cf is disabled
– dialparties.agi: Extension 105 cf is disabled
– dialparties.agi: Extension 106 cf is disabled
– dialparties.agi: Extension 101 do not disturb is disabled
– dialparties.agi: Extension 102 do not disturb is disabled
– dialparties.agi: Extension 103 do not disturb is disabled
– dialparties.agi: Extension 104 do not disturb is disabled
– dialparties.agi: Extension 105 do not disturb is disabled
– dialparties.agi: Extension 106 do not disturb is disabled
> dialparties.agi: extnum 101 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: DbDel CALLTRACE/101 - Caller ID is not defined
> dialparties.agi: extnum 102 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: DbDel CALLTRACE/102 - Caller ID is not defined
> dialparties.agi: extnum 103 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: DbDel CALLTRACE/103 - Caller ID is not defined
> dialparties.agi: extnum 104 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: DbDel CALLTRACE/104 - Caller ID is not defined
> dialparties.agi: extnum 105 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: DbDel CALLTRACE/105 - Caller ID is not defined
> dialparties.agi: extnum 106 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: DbDel CALLTRACE/106 - Caller ID is not defined
– dialparties.agi: Filtered ARG3: 101-102-103-104-105-106
> dialparties.agi: NODEST: 101 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
dialparties.agi: FMUNIQUE: SIP/OVH-CNIM-00000094, FMRERING: 23, FMREALPRERING: 85, FMGRPTIME: 60
> dialparties.agi: NODEST: 101 blkvm enabled macro already in dialopts: trM(auto-blkvm)
== Manager ‘admin’ logged off from 127.0.0.1
– AGI Script dialparties.agi completed, returning 0
– Executing [[email protected]:7] Dial(“SIP/OVH-CNIM-00000094”, “Local/[email protected]&Local/[email protected]|85|trM(auto-blkvm)”) in new stack
– Called [email protected]
– Executing [[email protected]:1] NoOp(“Local/[email protected],2”, “In FMPR 101 with 101”) in new stack
– Called [email protected]
– Executing [[email protected]:2] Set(“Local/[email protected],2”, “RingGroupMethod=”) in new stack
– Executing [[email protected]:1] NoOp(“Local/[email protected],2”, “In FMGL 101 with 102-103-104-105-106”) in new stack
– Executing [[email protected]:3] Set(“Local/[email protected],2”, “USE_CONFIRMATION=”) in new stack
– Executing [[email protected]:2] GotoIf(“Local/[email protected],2”, “0?dodnd”) in new stack
– Executing [[email protected]:4] Set(“Local/[email protected],2”, “RINGGROUP_INDEX=”) in new stack
– Executing [[email protected]:3] Wait(“Local/[email protected],2”, “1”) in new stack
– Executing [[email protected]:5] Macro(“Local/[email protected],2”, “simple-dial|101|85”) in new stack
– Executing [[email protected]:1] Set(“Local/[email protected],2”, “__EXTTOCALL=101”) in new stack
– Executing [[email protected]:2] Set(“Local/[email protected],2”, “RT=85”) in new stack
– Executing [[email protected]:3] Set(“Local/[email protected],2”, “CFUEXT=”) in new stack
– Executing [[email protected]:4] Set(“Local/[email protected],2”, “CFBEXT=”) in new stack
– Executing [[email protected]:5] Set(“Local/[email protected],2”, “CWI_TMP=”) in new stack
– Executing [[email protected]:6] Macro(“Local/[email protected],2”, “dial|85|tr|101”) in new stack
– Executing [[email protected]:1] GotoIf(“Local/[email protected],2”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [[email protected]:3] AGI(“Local/[email protected],2”, “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 ‘Anonymous’ number is 'anonymous’
dialparties.agi: USE_CONFIRMATION: 'FALSE’
dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘none’
– dialparties.agi: Added extension 101 to extension map
> dialparties.agi: Extension 101 has call screening off
– dialparties.agi: Extension 101 cf is disabled
– dialparties.agi: Extension 101 do not disturb is disabled
> dialparties.agi: extnum 101 has: cw: 1; hascfb: 0 [] hascfu: 0 []
dialparties.agi: ExtensionState: 0
– dialparties.agi: DbDel CALLTRACE/101 - Caller ID is not defined
– dialparties.agi: Filtered ARG3: 101
> dialparties.agi: NODEST: 101 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
> dialparties.agi: NODEST: 101 blkvm enabled macro already in dialopts: trM(auto-blkvm)
== Manager ‘admin’ logged off from 127.0.0.1
– AGI Script dialparties.agi completed, returning 0
– Executing [[email protected]:7] Dial(“Local/[email protected],2”, “SIP/101|85|trM(auto-blkvm)”) in new stack
– Called 101
– Local/[email protected],1 is ringing
– SIP/101-00000095 is ringing
– Executing [[email protected]:4] GotoIf(“Local/[email protected],2”, “0?dodnd”) in new stack
– Executing [[email protected]:5] Wait(“Local/[email protected],2”, “1”) in new stack
– Executing [[email protected]:6] GotoIf(“Local/[email protected],2”, “0?dodnd”) in new stack
– Executing [[email protected]:7] Wait(“Local/[email protected],2”, “23”) in new stack
– Executing [[email protected]:8] GotoIf(“Local/[email protected],2”, “0?dodnd”) in new stack
– Executing [[email protected]:9] DBdel(“Local/[email protected],2”, “FM/DND/101/SIP/OVH-CNIM-00000094”) in new stack
– DBdel: family=FM, key=DND/101/SIP/OVH-CNIM-00000094
– DBdel: Error deleting key from database.
– Executing [[email protected]:10] Macro(“Local/[email protected],2”, “dial|60|tr|102-103-104-105-106”) in new stack
– Executing [[email protected]:1] GotoIf(“Local/[email protected],2”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [[email protected]:3] AGI(“Local/[email protected],2”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
== Spawn extension (macro-dial, s, 7) exited non-zero on ‘Local/[email protected],2’ in macro ‘dial’
== Spawn extension (macro-simple-dial, s, 6) exited non-zero on ‘Local/[email protected],2’ in macro ‘simple-dial’
== Spawn extension (from-internal, FMPR-101, 5) exited non-zero on ‘Local/[email protected],2’
== Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/OVH-CNIM-00000094’ in macro ‘dial’
– Executing [[email protected]:1] Macro(“Local/[email protected],2”, “hangupcall”) in new stack
== Spawn extension (macro-dial, s, 3) exited non-zero on ‘Local/[email protected],2’ in macro ‘dial’
== Spawn extension (from-internal, FMGL-102-103-104-105-106, 10) exited non-zero on ‘Local/[email protected],2’
– Executing [[email protected]:1] GotoIf(“Local/[email protected],2”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [[email protected]:1] Macro(“Local/[email protected],2”, “hangupcall”) in new stack
– Executing [[email protected]:4] GotoIf(“Local/[email protected],2”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [[email protected]:1] GotoIf(“Local/[email protected],2”, “1?skiprg”) in new stack
– Executing [[email protected]:7] GotoIf(“Local/[email protected],2”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [[email protected]:9] Hangup(“Local/[email protected],2”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘Local/[email protected],2’ in macro ‘hangupcall’
== Spawn extension (macro-dial, h, 1) exited non-zero on ‘Local/[email protected],2’
– Goto (macro-hangupcall,s,4)
– Executing [[email protected]:4] GotoIf(“Local/[email protected],2”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [[email protected]:7] GotoIf(“Local/FMGL-102-103-1[email protected],2”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,9)
== Spawn extension (from-did-direct, 101, 18) exited non-zero on ‘SIP/OVH-CNIM-00000094’
– Executing [[email protected]:9] Hangup(“Local/[email protected],2”, “”) in new stack
– Executing [[email protected]:1] Macro(“SIP/OVH-CNIM-00000094”, “hangupcall”) in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘Local/[email protected],2’ in macro ‘hangupcall’
== Spawn extension (macro-dial, h, 1) exited non-zero on ‘Local/[email protected],2’
– Executing [[email protected]:1] GotoIf(“SIP/OVH-CNIM-00000094”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [[email protected]:4] GotoIf(“SIP/OVH-CNIM-00000094”, “0?skipblkvm”) in new stack
– Executing [[email protected]:5] NoOp(“SIP/OVH-CNIM-00000094”, “Cleaning Up Block VM Flag: BLKVM/101/SIP/OVH-CNIM-00000094”) in new stack
– Executing [[email protected]:6] NoOp(“SIP/OVH-CNIM-00000094”, “Deleting: BLKVM/101/SIP/OVH-CNIM-00000094 TRUE”) in new stack
– Executing [[email protected]:7] GotoIf(“SIP/OVH-CNIM-00000094”, “0?theend”) in new stack
– Executing [[email protected]:8] NoOp(“SIP/OVH-CNIM-00000094”, "Deleting: FM/DND/101/SIP/OVH-CNIM-00000094 ") in new stack
– Executing [[email protected]:9] Hangup(“SIP/OVH-CNIM-00000094”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/OVH-CNIM-00000094’ in macro ‘hangupcall’
== Spawn extension (macro-dial, h, 1) exited non-zero on ‘SIP/OVH-CNIM-00000094’
----------END OF LOG-----------------

Thanks in advance.

Rasmat.

Nobody ?