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:

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