6 seconds to hang-up

I am running the following system: CentOS release 5.5, Asterisk 1.8.3.3, FreePBX 2.8.0, using CounterPath X-Lite 3.0 phones on my computer.

When I call from one extension to another internally the call completes, stays up for 6 seconds and then hangs up. I’m calling from line 1 to line 2 on the x-lite to test but calls from other internal extensions all hang up after 6 sec.

I have done multiple Google searches on this and have tried various things to no avail. Any help would be greatly appreciated.

Here is what CLI reports:

[[email protected] ~]# asterisk -r
Verbosity is at least 3
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [[email protected]:1] ExecIf(“SIP/3545-00000004”, “0?Set(__RINGTIMER=0)”) in new stack
– Executing [[email protected]:2] Macro(“SIP/3545-00000004”, “exten-vm,novm,3545,0,0,0”) in new stack
– Executing [[email protected]:1] Macro(“SIP/3545-00000004”, “user-callerid,”) in new stack
– Executing [[email protected]:1] Set(“SIP/3545-00000004”, “AMPUSER=3545”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/3545-00000004”, “0?report”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/3545-00000004”, “1?Set(REALCALLERIDNUM=3545)”) in new stack
– Executing [[email protected]:4] Set(“SIP/3545-00000004”, “AMPUSER=3545”) in new stack
– Executing [[email protected]:5] Set(“SIP/3545-00000004”, “AMPUSERCIDNAME=Paul 2”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/3545-00000004”, “0?report”) in new stack
– Executing [[email protected]:7] Set(“SIP/3545-00000004”, “AMPUSERCID=3545”) in new stack
– Executing [[email protected]:8] Set(“SIP/3545-00000004”, “CALLERID(all)=“Paul 2” <3545>”) in new stack
– Executing [[email protected]:9] GotoIf(“SIP/3545-00000004”, “0?limit”) in new stack
– Executing [[email protected]:10] ExecIf(“SIP/3545-00000004”, “0?Set(GROUP(concurrency_limit)=3545)”) in new stack
– Executing [[email protected]:11] ExecIf(“SIP/3545-00000004”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:12] GosubIf(“SIP/3545-00000004”, “7?sub-ccss,s,1(macro-exten-vm,3545)”) in new stack
– Executing [[email protected]:1] ExecIf(“SIP/3545-00000004”, “0?Return()”) in new stack
– Executing [[email protected]:2] Set(“SIP/3545-00000004”, “CCSS_SETUP=TRUE”) in new stack
– Executing [[email protected]:3] GosubIf(“SIP/3545-00000004”, “7?monitor_config,1(macro-exten-vm,3545):monitor_default,1(macro-exten-vm,3545)”) in new stack
– Executing [[email protected]:1] Set(“SIP/3545-00000004”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/3545-00000004”, “1?set_monitor”) in new stack
– Goto (sub-ccss,monitor_config,5)
– Executing [[email protected]:5] Set(“SIP/3545-00000004”, “CALLCOMPLETION(cc_max_monitors)=”) in new stack
– Executing [[email protected]:6] Return(“SIP/3545-00000004”, “TRUE”) in new stack
– Executing [[email protected]:4] GosubIf(“SIP/3545-00000004”, “7?agent_config,1:agent_default,1”) in new stack
– Executing [[email protected]:1] Set(“SIP/3545-00000004”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
– Executing [[email protected]:2] Set(“SIP/3545-00000004”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
– Executing [[email protected]:3] Set(“SIP/3545-00000004”, “CALLCOMPLETION(ccbs_available_timer)=4800”) in new stack
– Executing [[email protected]:4] Set(“SIP/3545-00000004”, “CALLCOMPLETION(ccnr_available_timer)=7200”) in new stack
– Executing [[email protected]:5] Set(“SIP/3545-00000004”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [[email protected]:6] ExecIf(“SIP/3545-00000004”, “1?Set(CALLCOMPLETION(cc_recall_timer)=15)”) in new stack
– Executing [[email protected]:7] ExecIf(“SIP/3545-00000004”, “1?Set(CALLCOMPLETION(cc_max_agents)=5)”) in new stack
– Executing [[email protected]:8] ExecIf(“SIP/3545-00000004”, “1?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])”) in new stack
– Executing [[email protected]:9] Set(“SIP/3545-00000004”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [[email protected]:10] Return(“SIP/3545-00000004”, “”) in new stack
– Executing [[email protected]:5] Set(“SIP/3545-00000004”, “DB(AMPUSER/3545/ccss/last_number)=3545”) in new stack
– Executing [[email protected]:6] Return(“SIP/3545-00000004”, “”) in new stack
– Executing [[email protected]:13] GotoIf(“SIP/3545-00000004”, “0?continue”) in new stack
– Executing [[email protected]:14] Set(“SIP/3545-00000004”, “__TTL=64”) in new stack
– Executing [[email protected]:15] GotoIf(“SIP/3545-00000004”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,26)
– Executing [[email protected]:26] Set(“SIP/3545-00000004”, “CALLERID(number)=3545”) in new stack
– Executing [[email protected]:27] Set(“SIP/3545-00000004”, “CALLERID(name)=Paul 2”) in new stack
– Executing [[email protected]:28] Set(“SIP/3545-00000004”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:2] Set(“SIP/3545-00000004”, “RingGroupMethod=none”) in new stack
– Executing [[email protected]:3] Set(“SIP/3545-00000004”, “__EXTTOCALL=3545”) in new stack
– Executing [[email protected]:4] Set(“SIP/3545-00000004”, “__PICKUPMARK=3545”) in new stack
– Executing [[email protected]:5] Set(“SIP/3545-00000004”, “RT=”"") in new stack
– Executing [[email protected]:6] Macro(“SIP/3545-00000004”, “record-enable,3545,IN”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/3545-00000004”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [[email protected]:4] ExecIf(“SIP/3545-00000004”, “0?MacroExit()”) in new stack
– Executing [[email protected]:5] GotoIf(“SIP/3545-00000004”, “0?Group:OUT”) in new stack
– Goto (macro-record-enable,s,14)
– Executing [[email protected]:14] GotoIf(“SIP/3545-00000004”, “1?IN”) in new stack
– Goto (macro-record-enable,s,18)
– Executing [[email protected]:18] ExecIf(“SIP/3545-00000004”, “1?MacroExit()”) in new stack
– Executing [[email protected]:7] Macro(“SIP/3545-00000004”, “dial-one,”",tr,3545") in new stack
– Executing [[email protected]:1] Set(“SIP/3545-00000004”, “DEXTEN=3545”) in new stack
– Executing [[email protected]:2] Set(“SIP/3545-00000004”, “DIALSTATUS_CW=”) in new stack
– Executing [[email protected]:3] GosubIf(“SIP/3545-00000004”, “0?screen,1”) in new stack
– Executing [[email protected]:4] GosubIf(“SIP/3545-00000004”, “0?cf,1”) in new stack
– Executing [[email protected]:5] GotoIf(“SIP/3545-00000004”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [[email protected]:8] GotoIf(“SIP/3545-00000004”, “0?nodial”) in new stack
– Executing [[email protected]:9] GotoIf(“SIP/3545-00000004”, “0?continue”) in new stack
– Executing [[email protected]:10] Set(“SIP/3545-00000004”, “EXTHASCW=ENABLED”) in new stack
– Executing [[email protected]:11] GotoIf(“SIP/3545-00000004”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,23)
– Executing [[email protected]:23] GotoIf(“SIP/3545-00000004”, “1?next3:continue”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [[email protected]:24] ExecIf(“SIP/3545-00000004”, “1?Set(DIALSTATUS_CW=BUSY)”) in new stack
– Executing [[email protected]:25] GotoIf(“SIP/3545-00000004”, “0?nodial”) in new stack
– Executing [[email protected]:26] GosubIf(“SIP/3545-00000004”, “1?dstring,1:dlocal,1”) in new stack
– Executing [[email protected]:1] Set(“SIP/3545-00000004”, “DSTRING=”) in new stack
– Executing [[email protected]:2] Set(“SIP/3545-00000004”, “DEVICES=3545”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/3545-00000004”, “0?Return()”) in new stack
– Executing [[email protected]:4] ExecIf(“SIP/3545-00000004”, “0?Set(DEVICES=545)”) in new stack
– Executing [[email protected]:5] Set(“SIP/3545-00000004”, “LOOPCNT=1”) in new stack
– Executing [[email protected]:6] Set(“SIP/3545-00000004”, “ITER=1”) in new stack
– Executing [[email protected]:7] Set(“SIP/3545-00000004”, “THISDIAL=SIP/3545”) in new stack
– Executing [[email protected]:8] GosubIf(“SIP/3545-00000004”, “1?zap2dahdi,1”) in new stack
– Executing [[email protected]:1] ExecIf(“SIP/3545-00000004”, “0?Return()”) in new stack
– Executing [[email protected]:2] Set(“SIP/3545-00000004”, “NEWDIAL=”) in new stack
– Executing [[email protected]:3] Set(“SIP/3545-00000004”, “LOOPCNT2=1”) in new stack
– Executing [[email protected]:4] Set(“SIP/3545-00000004”, “ITER2=1”) in new stack
– Executing [[email protected]:5] Set(“SIP/3545-00000004”, “THISPART2=SIP/3545”) in new stack
– Executing [[email protected]:6] ExecIf(“SIP/3545-00000004”, “0?Set(THISPART2=DAHDI/3545)”) in new stack
– Executing [[email protected]:7] Set(“SIP/3545-00000004”, “NEWDIAL=SIP/3545&”) in new stack
– Executing [[email protected]:8] Set(“SIP/3545-00000004”, “ITER2=2”) in new stack
– Executing [[email protected]:9] GotoIf(“SIP/3545-00000004”, “0?begin2”) in new stack
– Executing [[email protected]:10] Set(“SIP/3545-00000004”, “THISDIAL=SIP/3545”) in new stack
– Executing [[email protected]:11] Return(“SIP/3545-00000004”, “”) in new stack
– Executing [[email protected]:9] Set(“SIP/3545-00000004”, “DSTRING=SIP/3545&”) in new stack
– Executing [[email protected]:10] Set(“SIP/3545-00000004”, “ITER=2”) in new stack
– Executing [[email protected]:11] GotoIf(“SIP/3545-00000004”, “0?begin”) in new stack
– Executing [[email protected]:12] Set(“SIP/3545-00000004”, “DSTRING=SIP/3545”) in new stack
– Executing [[email protected]:13] Return(“SIP/3545-00000004”, “”) in new stack
– Executing [[email protected]:27] GotoIf(“SIP/3545-00000004”, “0?nodial”) in new stack
– Executing [[email protected]:28] GotoIf(“SIP/3545-00000004”, “1?skiptrace”) in new stack
– Goto (macro-dial-one,s,30)
– Executing [[email protected]:30] Set(“SIP/3545-00000004”, “D_OPTIONS=tr”) in new stack
– Executing [[email protected]:31] ExecIf(“SIP/3545-00000004”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [[email protected]:32] ExecIf(“SIP/3545-00000004”, “0?SIPAddHeader()”) in new stack
– Executing [[email protected]:33] ExecIf(“SIP/3545-00000004”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [[email protected]:34] GosubIf(“SIP/3545-00000004”, “0?qwait,1”) in new stack
– Executing [[email protected]:35] Set(“SIP/3545-00000004”, “__CWIGNORE=”) in new stack
– Executing [[email protected]:36] Set(“SIP/3545-00000004”, “__KEEPCID=TRUE”) in new stack
– Executing [[email protected]:37] GotoIf(“SIP/3545-00000004”, “0?usegoto,1”) in new stack
– Executing [[email protected]:38] GotoIf(“SIP/3545-00000004”, “0?godial”) in new stack
– Executing [[email protected]:39] Set(“SIP/3545-00000004”, “CONNECTEDLINE(name,i)=Paul 2”) in new stack
– Executing [[email protected]:40] Set(“SIP/3545-00000004”, “CONNECTEDLINE(num)=3545”) in new stack
– Executing [[email protected]:41] Set(“SIP/3545-00000004”, “D_OPTIONS=trI”) in new stack
– Executing [[email protected]:42] Dial(“SIP/3545-00000004”, “SIP/3545,”",trI") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called 3545
– SIP/3545-00000005 is ringing
– SIP/3545-00000005 is ringing
– Connected line update to SIP/3545-00000004 prevented.
– SIP/3545-00000005 answered SIP/3545-00000004
– Executing [[email protected]:1] Macro(“SIP/3545-00000004”, “hangupcall,”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/3545-00000004”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [[email protected]:3] Hangup(“SIP/3545-00000004”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 3) exited non-zero on ‘SIP/3545-00000004’ in macro ‘hangupcall’
== Spawn extension (macro-dial-one, s, 42) exited non-zero on ‘SIP/3545-00000004’ in macro ‘dial-one’
== Spawn extension (macro-exten-vm, s, 7) exited non-zero on ‘SIP/3545-00000004’ in macro ‘exten-vm’
== Spawn extension (from-internal, 3545, 2) exited non-zero on 'SIP/3545-00000004’
astconf1*CLI>

By any chance, in your SIP settings, for your IP Configuration do you have Dynamic IP checked? If so, try using Static IP (let it auto-configure) and see if that eliminates the problem. If it does, but your broband provider changes your IP address from time to time, the solution I use can be found here:

A Perl script to rewrite the “static” IP address in the FreePBX Asterisk SIP Settings when it is changed by your ISP

I don’t know if this is your issue, but what you’re describing exactly matches one of the effects I was seeing when trying to use the Dynamic IP option.

SIP settings were set to Static IP. I did find that the local network that my x-lite phone was on was missing. Added the network and phone and internal calls now work. Thanks for pointing me in the right direction.