Ring timer haven't effect

Hi,
I have a strange problem.
External and internal calls are closed after 20 seconds. I have change the ring time at 120 seconds but haven’t effect.
Can you help me?
Thanks.

FreePBX v. 2.11.0.27

Information:
when the ring time is 120 seconds the vm tell that user is “Busy”, when is 15 seconds (default) “Unavaiable”.

[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] Set(“SIP/eutelia-00000003”, “GROUP()=OUT_3”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Goto(“SIP/eutelia-00000003”, “from-trunk,0699xxxxxx,1”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (from-trunk,0699xxxxxx,1)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] Set(“SIP/eutelia-00000003”, “__FROM_DID=0699xxxxxx”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Set(“SIP/eutelia-00000003”, “CHANNEL(language)=it”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:3] Gosub(“SIP/eutelia-00000003”, “cidlookup,cidlookup_2,1()”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/eutelia-00000003”, “1?cidlookup,cidlookup_return,1”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (cidlookup,cidlookup_return,1)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/eutelia-00000003”, “1?Set(CALLERID(name)=Sconosciuto)”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Return(“SIP/eutelia-00000003”, “”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:4] Gosub(“SIP/eutelia-00000003”, “app-blacklist-check,s,1()”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/eutelia-00000003”, “0?blacklisted”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Set(“SIP/eutelia-00000003”, “CALLED_BLACKLIST=1”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:3] Return(“SIP/eutelia-00000003”, “”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:5] Set(“SIP/eutelia-00000003”, “CDR(did)=0699xxxxxx”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/eutelia-00000003”, “0 ?Set(CALLERID(name)=338xxxxxxx)”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:7] Set(“SIP/eutelia-00000003”, “CHANNEL(musicclass)=default”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:8] Set(“SIP/eutelia-00000003”, “__MOHCLASS=default”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:9] Set(“SIP/eutelia-00000003”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:10] Set(“SIP/eutelia-00000003”, “CALLERPRES()=allowed_not_screened”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:11] Goto(“SIP/eutelia-00000003”, “ext-group,600,1”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (ext-group,600,1)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] Macro(“SIP/eutelia-00000003”, “user-callerid,”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] Set(“SIP/eutelia-00000003”, “TOUCH_MONITOR=1395399579.3”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Set(“SIP/eutelia-00000003”, “AMPUSER=338xxxxxxx”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/eutelia-00000003”, “0?report”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/eutelia-00000003”, “1?Set(REALCALLERIDNUM=338xxxxxxx)”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:5] Set(“SIP/eutelia-00000003”, “AMPUSER=”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/eutelia-00000003”, “0?limit”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:7] Set(“SIP/eutelia-00000003”, “AMPUSERCIDNAME=”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/eutelia-00000003”, “1?report”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (macro-user-callerid,s,15)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/eutelia-00000003”, “0?continue”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:16] ExecIf(“SIP/eutelia-00000003”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:17] Set(“SIP/eutelia-00000003”, “__TTL=64”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:18] GotoIf(“SIP/eutelia-00000003”, “1?continue”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (macro-user-callerid,s,29)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:29] Set(“SIP/eutelia-00000003”, “CALLERID(number)=338xxxxxxx”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:30] Set(“SIP/eutelia-00000003”, “CALLERID(name)=Sconosciuto”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:31] Set(“SIP/eutelia-00000003”, “CDR(cnum)=338xxxxxxx”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:32] Set(“SIP/eutelia-00000003”, “CDR(cnam)=Sconosciuto”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:33] Set(“SIP/eutelia-00000003”, “CHANNEL(language)=it”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Macro(“SIP/eutelia-00000003”, “blkvm-setifempty,”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/eutelia-00000003”, “1?init”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:4] Set(“SIP/eutelia-00000003”, “__BLKVM_CHANNEL=SIP/eutelia-00000003”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:5] Set(“SIP/eutelia-00000003”, “SHARED(BLKVM,SIP/eutelia-00000003)=TRUE”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:6] Set(“SIP/eutelia-00000003”, “GOSUB_RETVAL=TRUE”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:7] MacroExit(“SIP/eutelia-00000003”, “”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/eutelia-00000003”, “1?skipov”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (ext-group,600,6)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:6] Set(“SIP/eutelia-00000003”, “RRNODEST=”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:7] Set(“SIP/eutelia-00000003”, “__NODEST=600”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/eutelia-00000003”, “0?sub-rgsetcid,s,1()”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:9] Gosub(“SIP/eutelia-00000003”, “sub-record-check,s,1(rg,600,dontcare)”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] Set(“SIP/eutelia-00000003”, “REC_POLICY_MODE_SAVE=”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/eutelia-00000003”, “1?check”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (sub-record-check,s,7)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:7] Set(“SIP/eutelia-00000003”, “__MON_FMT=wav”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/eutelia-00000003”, “1?next”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (sub-record-check,s,11)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/eutelia-00000003”, “0?Return()”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/eutelia-00000003”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/eutelia-00000003”, “0?rg,1”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:14] Set(“SIP/eutelia-00000003”, “__REC_STATUS=INITIALIZED”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:15] Set(“SIP/eutelia-00000003”, “NOW=1395399579”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:16] Set(“SIP/eutelia-00000003”, “__DAY=21”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:17] Set(“SIP/eutelia-00000003”, “__MONTH=03”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:18] Set(“SIP/eutelia-00000003”, “__YEAR=2014”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:19] Set(“SIP/eutelia-00000003”, “__TIMESTR=20140321-115939”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:20] Set(“SIP/eutelia-00000003”, “__FROMEXTEN=338xxxxxxx”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:21] Set(“SIP/eutelia-00000003”, “__CALLFILENAME=rg-600-338xxxxxxx-20140321-115939-1395399579.3”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:22] Goto(“SIP/eutelia-00000003”, “rg,1”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Goto (sub-record-check,rg,1)
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] GosubIf(“SIP/eutelia-00000003”, “0?record,1(rg,dontcare,338xxxxxxx)”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Return(“SIP/eutelia-00000003”, “”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:10] Set(“SIP/eutelia-00000003”, “RingGroupMethod=ringall”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:11] Macro(“SIP/eutelia-00000003”, “dial,180,Ttr,1011-1012”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/eutelia-00000003”, “0?dial”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] SetMusicOnHold(“SIP/eutelia-00000003”, “default”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:3] AGI(“SIP/eutelia-00000003”, “dialparties.agi”) in new stack
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: dialparties.agi: Caller ID name is ‘Sconosciuto’ number is ‘338xxxxxxx’
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: Added extension 1011 to extension map
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: Added extension 1012 to extension map
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: Extension 1011 cf is disabled
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: Extension 1012 cf is disabled
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: Extension 1011 do not disturb is disabled
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: Extension 1012 do not disturb is disabled
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: > dialparties.agi: extnum 1011 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: dbset CALLTRACE/1011 to 338xxxxxxx
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: > dialparties.agi: extnum 1012 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: dbset CALLTRACE/1012 to 338xxxxxxx
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – dialparties.agi: Filtered ARG3: 1011-1012
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: > dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: TtrM(auto-blkvm)
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: > dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: TtrM(auto-blkvm)
[2014-03-21 11:59:39] VERBOSE[4506] res_agi.c: – <SIP/eutelia-00000003>AGI Script dialparties.agi completed, returning 0
[2014-03-21 11:59:39] VERBOSE[4506] pbx.c: – Executing [[email protected]:7] Dial(“SIP/eutelia-00000003”, “SIP/1011&SIP/1012,180,TtrM(auto-blkvm)”) in new stack
[2014-03-21 11:59:39] WARNING[4506] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Unknown)
[2014-03-21 11:59:39] VERBOSE[4506] netsock2.c: == Using SIP RTP TOS bits 184
[2014-03-21 11:59:39] VERBOSE[4506] netsock2.c: == Using SIP RTP CoS mark 5
[2014-03-21 11:59:39] VERBOSE[4506] app_dial.c: – Called SIP/1012
[2014-03-21 11:59:40] VERBOSE[4506] app_dial.c: – SIP/1012-00000004 is ringing
[2014-03-21 11:59:40] VERBOSE[4506] app_dial.c: – SIP/1012-00000004 is ringing
[2014-03-21 11:59:43] VERBOSE[4391] dnsmgr.c: > doing dnsmgr_lookup for ‘sip.messagenet.it
[2014-03-21 11:59:43] VERBOSE[4391] dnsmgr.c: > doing dnsmgr_lookup for ‘voip.eutelia.it
[2014-03-21 11:59:43] VERBOSE[4391] dnsmgr.c: > doing dnsmgr_lookup for ‘voip.eutelia.it
[2014-03-21 11:59:55] VERBOSE[4391] chan_sip.c: – Got SIP response 486 “Busy here” back from 192.168.100.102:24791
[2014-03-21 11:59:55] VERBOSE[4506] app_dial.c: – SIP/1012-00000004 is busy
[2014-03-21 11:59:55] VERBOSE[4506] app_dial.c: == Everyone is busy/congested at this time (2:1/0/1)
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:8] Set(“SIP/eutelia-00000003”, “DIALSTATUS=BUSY”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:9] GosubIf(“SIP/eutelia-00000003”, “0?BUSY,1”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:12] Gosub(“SIP/eutelia-00000003”, “sub-record-cancel,s,1()”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] Set(“SIP/eutelia-00000003”, “__REC_POLICY_MODE=”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/eutelia-00000003”, “1?Return()”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:13] Set(“SIP/eutelia-00000003”, “RingGroupMethod=”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/eutelia-00000003”, “0?nodest”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:15] Set(“SIP/eutelia-00000003”, “__NODEST=”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:16] Macro(“SIP/eutelia-00000003”, “blkvm-clr,”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] Set(“SIP/eutelia-00000003”, “SHARED(BLKVM,SIP/eutelia-00000003)=”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Set(“SIP/eutelia-00000003”, “GOSUB_RETVAL=”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:3] MacroExit(“SIP/eutelia-00000003”, “”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:17] Goto(“SIP/eutelia-00000003”, “app-blackhole,hangup,1”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Goto (app-blackhole,hangup,1)
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:1] NoOp(“SIP/eutelia-00000003”, “Blackhole Dest: Hangup”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: – Executing [[email protected]:2] Hangup(“SIP/eutelia-00000003”, “”) in new stack
[2014-03-21 11:59:55] VERBOSE[4506] pbx.c: == Spawn extension (app-blackhole, hangup, 2) exited non-zero on ‘SIP/eutelia-00000003’

Is a problem of softphone.
I use Linphone.