External soft phones cant call each other

I have two external soft phones that cannot call each other. I have been looking at the logs and all over the internet, but I have no idea what the logs are trying to tell me. Can someone please help

I have attached the log message

Thank you very much…

[2011-11-23 11:44:31] VERBOSE[3062] chan_sip.c: – Registered SIP ‘4052’ at 50.129.188.125:36444
[2011-11-23 11:44:31] NOTICE[3062] chan_sip.c: Peer ‘4052’ is now Reachable. (6ms / 2000ms)
[2011-11-23 11:44:35] VERBOSE[3062] netsock2.c: == Using SIP RTP TOS bits 184
[2011-11-23 11:44:35] VERBOSE[3062] netsock2.c: == Using SIP RTP CoS mark 5
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/4051-0000003b”, “0?Set(__RINGTIMER=0)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] Macro(“SIP/4051-0000003b”, “exten-vm,novm,4052,0,0,0”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] Macro(“SIP/4051-0000003b”, “user-callerid,”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] Set(“SIP/4051-0000003b”, “AMPUSER=4051”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/4051-0000003b”, “0?report”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/4051-0000003b”, “1?Set(REALCALLERIDNUM=4051)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] Set(“SIP/4051-0000003b”, “AMPUSER=4051”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] Set(“SIP/4051-0000003b”, “AMPUSERCIDNAME=Dylan”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/4051-0000003b”, “0?report”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:7] Set(“SIP/4051-0000003b”, “AMPUSERCID=4051”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:8] Set(“SIP/4051-0000003b”, “CALLERID(all)=“Dylan” <4051>”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/4051-0000003b”, “0?limit”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:10] ExecIf(“SIP/4051-0000003b”, “0?Set(GROUP(concurrency_limit)=4051)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:11] GosubIf(“SIP/4051-0000003b”, “7?sub-ccss,s,1(macro-exten-vm,4052)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/4051-0000003b”, “0?Return()”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] Set(“SIP/4051-0000003b”, “CCSS_SETUP=TRUE”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/4051-0000003b”, “7?monitor_config,1(macro-exten-vm,4052):monitor_default,1(macro-exten-vm,4052)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/4051-0000003b”, “1?set_monitor”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (sub-ccss,monitor_config,5)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(cc_max_monitors)=”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:6] Return(“SIP/4051-0000003b”, “TRUE”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/4051-0000003b”, “7?agent_config,1:agent_default,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(ccbs_available_timer)=4800”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(ccnr_available_timer)=7200”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/4051-0000003b”, “1?Set(CALLCOMPLETION(cc_recall_timer)=15)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/4051-0000003b”, “1?Set(CALLCOMPLETION(cc_max_agents)=5)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:8] ExecIf(“SIP/4051-0000003b”, “1?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:9] Set(“SIP/4051-0000003b”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:10] Return(“SIP/4051-0000003b”, “”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] Set(“SIP/4051-0000003b”, “DB(AMPUSER/4051/ccss/last_number)=4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:6] Return(“SIP/4051-0000003b”, “”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/4051-0000003b”, “0?Set(CHANNEL(language)=)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/4051-0000003b”, “0?continue”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:14] Set(“SIP/4051-0000003b”, “__TTL=64”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/4051-0000003b”, “1?continue”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-user-callerid,s,26)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:26] Set(“SIP/4051-0000003b”, “CALLERID(number)=4051”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:27] Set(“SIP/4051-0000003b”, “CALLERID(name)=Dylan”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:28] Set(“SIP/4051-0000003b”, “CHANNEL(language)=en”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] Set(“SIP/4051-0000003b”, “RingGroupMethod=none”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] Set(“SIP/4051-0000003b”, “__EXTTOCALL=4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] Set(“SIP/4051-0000003b”, “__PICKUPMARK=4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] Set(“SIP/4051-0000003b”, “RT=”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:6] Macro(“SIP/4051-0000003b”, “record-enable,4052,IN”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/4051-0000003b”, “1?check”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-record-enable,s,4)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/4051-0000003b”, “0?MacroExit()”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/4051-0000003b”, “0?Group:OUT”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-record-enable,s,14)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/4051-0000003b”, “1?IN”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-record-enable,s,18)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:18] ExecIf(“SIP/4051-0000003b”, “1?MacroExit()”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/4051-0000003b”, “1?macrodial”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-exten-vm,s,13)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/4051-0000003b”, “0?clrheader,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:14] Macro(“SIP/4051-0000003b”, “dial-one,tr,4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] Set(“SIP/4051-0000003b”, “DEXTEN=4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] Set(“SIP/4051-0000003b”, “DIALSTATUS_CW=”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/4051-0000003b”, “0?screen,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/4051-0000003b”, “0?cf,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/4051-0000003b”, “1?skip1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-dial-one,s,8)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/4051-0000003b”, “0?nodial”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/4051-0000003b”, “0?continue”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:10] Set(“SIP/4051-0000003b”, “EXTHASCW=ENABLED”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/4051-0000003b”, “0?next1:cwinusebusy”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-dial-one,s,23)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:23] GotoIf(“SIP/4051-0000003b”, “1?next3:continue”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-dial-one,s,24)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:24] ExecIf(“SIP/4051-0000003b”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:25] GotoIf(“SIP/4051-0000003b”, “0?nodial”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:26] GosubIf(“SIP/4051-0000003b”, “1?dstring,1:dlocal,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] Set(“SIP/4051-0000003b”, “DSTRING=”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] Set(“SIP/4051-0000003b”, “DEVICES=4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/4051-0000003b”, “0?Return()”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/4051-0000003b”, “0?Set(DEVICES=052)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] Set(“SIP/4051-0000003b”, “LOOPCNT=1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:6] Set(“SIP/4051-0000003b”, “ITER=1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:7] Set(“SIP/4051-0000003b”, “THISDIAL=SIP/4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/4051-0000003b”, “1?zap2dahdi,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/4051-0000003b”, “0?Return()”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] Set(“SIP/4051-0000003b”, “NEWDIAL=”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] Set(“SIP/4051-0000003b”, “LOOPCNT2=1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:4] Set(“SIP/4051-0000003b”, “ITER2=1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:5] Set(“SIP/4051-0000003b”, “THISPART2=SIP/4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/4051-0000003b”, “0?Set(THISPART2=DAHDI/4052)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:7] Set(“SIP/4051-0000003b”, “NEWDIAL=SIP/4052&”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:8] Set(“SIP/4051-0000003b”, “ITER2=2”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/4051-0000003b”, “0?begin2”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:10] Set(“SIP/4051-0000003b”, “THISDIAL=SIP/4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:11] Return(“SIP/4051-0000003b”, “”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:9] Set(“SIP/4051-0000003b”, “DSTRING=SIP/4052&”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:10] Set(“SIP/4051-0000003b”, “ITER=2”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/4051-0000003b”, “0?begin”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:12] Set(“SIP/4051-0000003b”, “DSTRING=SIP/4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:13] Return(“SIP/4051-0000003b”, “”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:27] GotoIf(“SIP/4051-0000003b”, “0?nodial”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:28] GotoIf(“SIP/4051-0000003b”, “1?skiptrace”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Goto (macro-dial-one,s,30)
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:30] Set(“SIP/4051-0000003b”, “D_OPTIONS=tr”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:31] ExecIf(“SIP/4051-0000003b”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:32] ExecIf(“SIP/4051-0000003b”, “0?SIPAddHeader()”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:33] ExecIf(“SIP/4051-0000003b”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:34] GosubIf(“SIP/4051-0000003b”, “0?qwait,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:35] Set(“SIP/4051-0000003b”, “__CWIGNORE=”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:36] Set(“SIP/4051-0000003b”, “__KEEPCID=TRUE”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:37] GotoIf(“SIP/4051-0000003b”, “0?usegoto,1”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:38] GotoIf(“SIP/4051-0000003b”, “0?godial”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:39] Set(“SIP/4051-0000003b”, “CONNECTEDLINE(name,i)=Ben”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:40] Set(“SIP/4051-0000003b”, “CONNECTEDLINE(num)=4052”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:41] Set(“SIP/4051-0000003b”, “D_OPTIONS=trI”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] pbx.c: – Executing [[email protected]:42] Dial(“SIP/4051-0000003b”, “SIP/4052,trI”) in new stack
[2011-11-23 11:44:35] VERBOSE[5450] netsock2.c: == Using SIP RTP TOS bits 184
[2011-11-23 11:44:35] VERBOSE[5450] netsock2.c: == Using SIP RTP CoS mark 5
[2011-11-23 11:44:35] VERBOSE[5450] app_dial.c: – Called SIP/4052
[2011-11-23 11:44:35] VERBOSE[5450] app_dial.c: – Connected line update to SIP/4051-0000003b prevented.
[2011-11-23 11:44:42] WARNING[3062] chan_sip.c: Retransmission timeout reached on transmission [email protected]:5060 for seqno 102 (Critical Request) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6400ms with no response
[2011-11-23 11:44:42] WARNING[3062] chan_sip.c: Hanging up call [email protected]:5060 - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
[2011-11-23 11:44:42] VERBOSE[5450] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:43] ExecIf(“SIP/4051-0000003b”, “0?Set(DIALSTATUS=)”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:44] GosubIf(“SIP/4051-0000003b”, “0?s-CHANUNAVAIL,1”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:45] MacroExit(“SIP/4051-0000003b”, “”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/4051-0000003b”, “0?exit”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:16] Set(“SIP/4051-0000003b”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:17] GosubIf(“SIP/4051-0000003b”, “0?docfu,1”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:18] GosubIf(“SIP/4051-0000003b”, “0?docfb,1”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:19] Set(“SIP/4051-0000003b”, “DIALSTATUS=CHANUNAVAIL”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:20] ExecIf(“SIP/4051-0000003b”, “0?MacroExit()”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/4051-0000003b”, “1?s-CHANUNAVAIL,1”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Goto (macro-exten-vm,s-CHANUNAVAIL,1)
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/4051-0000003b”, “0?exit,1”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:2] PlayTones(“SIP/4051-0000003b”, “congestion”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:3] Congestion(“SIP/4051-0000003b”, “10”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] app_macro.c: == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on ‘SIP/4051-0000003b’ in macro ‘exten-vm’
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: == Spawn extension (from-internal, 4052, 2) exited non-zero on ‘SIP/4051-0000003b’
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/4051-0000003b”, “”) in new stack
[2011-11-23 11:44:42] VERBOSE[5450] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/4051-0000003b’

I think you’ll need to redo your test with higher levels of verbosity.

Use the Asterisk CLI module and issue this command:

core set verbose 10

You may also want to enable SIP debugging (the command depends upon which version of Asterisk you’re running - use Google to find it).

Then post your new logs.

Based upon what you posted, I’d guess a NAT issue. Have you properly configured the Asterisk SIP Settings module and forwarded UDP Ports 5060 and 10000 to 20000?

I confirm it is a NAT problem: UDP Ports 5060 and 10000 to 20000 must be open on server side.
Stun (stun.ekiga,net) and proxy server (your Asterisk server) should be enabled on client side.

Here is the new log. I now have an IP phone and a soft phone. Everything works out fine within the network, however I cant seem to get the internal IP phone to call out to the External phone(calling from the external phone to the internal phone works). I have verified that all the correct ports are open. I understand that this system seems to have issues with NAT, but is there any way to get around them? Any other ports I can open up? Anything? Thanks for all the help!

[2011-12-01 18:51:45] VERBOSE[3068] chan_sip.c: – Registered SIP ‘4502’ at 66.87.95.31:51775
[2011-12-01 18:51:50] WARNING[3068] chan_sip.c: Retransmission timeout reached on transmission 0RTJ3LNxAcIEi2HD73tXpo8dPQ7kpn90 for seqno 16052 (Critical Response) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6400ms with no response
[2011-12-01 18:51:50] WARNING[3068] chan_sip.c: Hanging up call 0RTJ3LNxAcIEi2HD73tXpo8dPQ7kpn90 - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
[2011-12-01 18:51:50] VERBOSE[3490] pbx.c: – Executing [[email protected]:1] Macro(“SIP/4502-00000007”, “hangupcall,”) in new stack
[2011-12-01 18:51:50] VERBOSE[3490] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/4502-00000007”, “1?theend”) in new stack
[2011-12-01 18:51:50] VERBOSE[3490] pbx.c: – Goto (macro-hangupcall,s,3)
[2011-12-01 18:51:50] VERBOSE[3490] pbx.c: – Executing [[email protected]:3] Hangup(“SIP/4502-00000007”, “”) in new stack
[2011-12-01 18:51:50] VERBOSE[3490] app_macro.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on ‘SIP/4502-00000007’ in macro ‘hangupcall’
[2011-12-01 18:51:50] VERBOSE[3490] features.c: == Spawn extension (macro-dial-one, h, 1) exited non-zero on ‘SIP/4502-00000007’
[2011-12-01 18:51:50] VERBOSE[3490] app_macro.c: == Spawn extension (macro-dial-one, s, 42) exited non-zero on ‘SIP/4502-00000007’ in macro ‘dial-one’
[2011-12-01 18:51:50] VERBOSE[3490] app_macro.c: == Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘SIP/4502-00000007’ in macro ‘exten-vm’
[2011-12-01 18:51:50] VERBOSE[3490] pbx.c: == Spawn extension (from-internal, 4501, 2) exited non-zero on ‘SIP/4502-00000007’
[2011-12-01 18:53:01] NOTICE[3068] chan_sip.c: Peer ‘4502’ is now Reachable. (1490ms / 2000ms)
[2011-12-01 18:53:05] VERBOSE[3068] netsock2.c: == Using SIP RTP TOS bits 184
[2011-12-01 18:53:05] VERBOSE[3068] netsock2.c: == Using SIP RTP CoS mark 5
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/4501-00000009”, “0?Set(__RINGTIMER=0)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] Macro(“SIP/4501-00000009”, “exten-vm,novm,4502,0,0,0”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] Macro(“SIP/4501-00000009”, “user-callerid,”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] Set(“SIP/4501-00000009”, “AMPUSER=4501”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/4501-00000009”, “0?report”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/4501-00000009”, “1?Set(REALCALLERIDNUM=4501)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] Set(“SIP/4501-00000009”, “AMPUSER=4501”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] Set(“SIP/4501-00000009”, “AMPUSERCIDNAME=Dylan Turnbull”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/4501-00000009”, “0?report”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:7] Set(“SIP/4501-00000009”, “AMPUSERCID=4501”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:8] Set(“SIP/4501-00000009”, “CALLERID(all)=“Dylan Turnbull” <4501>”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/4501-00000009”, “0?limit”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:10] ExecIf(“SIP/4501-00000009”, “0?Set(GROUP(concurrency_limit)=4501)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:11] GosubIf(“SIP/4501-00000009”, “7?sub-ccss,s,1(macro-exten-vm,4502)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/4501-00000009”, “0?Return()”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] Set(“SIP/4501-00000009”, “CCSS_SETUP=TRUE”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/4501-00000009”, “7?monitor_config,1(macro-exten-vm,4502):monitor_default,1(macro-exten-vm,4502)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] Set(“SIP/4501-00000009”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/4501-00000009”, “1?set_monitor”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (sub-ccss,monitor_config,5)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] Set(“SIP/4501-00000009”, “CALLCOMPLETION(cc_max_monitors)=”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:6] Return(“SIP/4501-00000009”, “TRUE”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/4501-00000009”, “7?agent_config,1:agent_default,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] Set(“SIP/4501-00000009”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] Set(“SIP/4501-00000009”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] Set(“SIP/4501-00000009”, “CALLCOMPLETION(ccbs_available_timer)=4800”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] Set(“SIP/4501-00000009”, “CALLCOMPLETION(ccnr_available_timer)=7200”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] Set(“SIP/4501-00000009”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/4501-00000009”, “1?Set(CALLCOMPLETION(cc_recall_timer)=15)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/4501-00000009”, “1?Set(CALLCOMPLETION(cc_max_agents)=5)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:8] ExecIf(“SIP/4501-00000009”, “1?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:9] Set(“SIP/4501-00000009”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:10] Return(“SIP/4501-00000009”, “”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] Set(“SIP/4501-00000009”, “DB(AMPUSER/4501/ccss/last_number)=4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:6] Return(“SIP/4501-00000009”, “”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/4501-00000009”, “0?Set(CHANNEL(language)=)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/4501-00000009”, “0?continue”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:14] Set(“SIP/4501-00000009”, “__TTL=64”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]rid:15] GotoIf(“SIP/4501-00000009”, “1?continue”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-user-callerid,s,26)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:26] Set(“SIP/4501-00000009”, “CALLERID(number)=4501”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:27] Set(“SIP/4501-00000009”, “CALLERID(name)=Dylan Turnbull”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:28] Set(“SIP/4501-00000009”, “CHANNEL(language)=en”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] Set(“SIP/4501-00000009”, “RingGroupMethod=none”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] Set(“SIP/4501-00000009”, “__EXTTOCALL=4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] Set(“SIP/4501-00000009”, “__PICKUPMARK=4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] Set(“SIP/4501-00000009”, “RT=”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:6] Macro(“SIP/4501-00000009”, “record-enable,4502,IN”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/4501-00000009”, “1?check”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-record-enable,s,4)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/4501-00000009”, “0?MacroExit()”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/4501-00000009”, “0?Group:OUT”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-record-enable,s,14)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/4501-00000009”, “1?IN”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-record-enable,s,18)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:18] ExecIf(“SIP/4501-00000009”, “1?MacroExit()”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/4501-00000009”, “1?macrodial”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-exten-vm,s,13)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/4501-00000009”, “0?clrheader,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:14] Macro(“SIP/4501-00000009”, “dial-one,tr,4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] Set(“SIP/4501-00000009”, “DEXTEN=4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] Set(“SIP/4501-00000009”, “DIALSTATUS_CW=”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/4501-00000009”, “0?screen,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/4501-00000009”, “0?cf,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/4501-00000009”, “1?skip1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-dial-one,s,8)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/4501-00000009”, “0?nodial”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/4501-00000009”, “0?continue”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:10] Set(“SIP/4501-00000009”, “EXTHASCW=ENABLED”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/4501-00000009”, “0?next1:cwinusebusy”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-dial-one,s,23)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:23] GotoIf(“SIP/4501-00000009”, “1?next3:continue”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-dial-one,s,24)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:24] ExecIf(“SIP/4501-00000009”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:25] GotoIf(“SIP/4501-00000009”, “0?nodial”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:26] GosubIf(“SIP/4501-00000009”, “1?dstring,1:dlocal,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] Set(“SIP/4501-00000009”, “DSTRING=”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] Set(“SIP/4501-00000009”, “DEVICES=4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/4501-00000009”, “0?Return()”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/4501-00000009”, “0?Set(DEVICES=502)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] Set(“SIP/4501-00000009”, “LOOPCNT=1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:6] Set(“SIP/4501-00000009”, “ITER=1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:7] Set(“SIP/4501-00000009”, “THISDIAL=SIP/4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/4501-00000009”, “1?zap2dahdi,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/4501-00000009”, “0?Return()”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] Set(“SIP/4501-00000009”, “NEWDIAL=”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] Set(“SIP/4501-00000009”, “LOOPCNT2=1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:4] Set(“SIP/4501-00000009”, “ITER2=1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:5] Set(“SIP/4501-00000009”, “THISPART2=SIP/4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/4501-00000009”, “0?Set(THISPART2=DAHDI/4502)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:7] Set(“SIP/4501-00000009”, “NEWDIAL=SIP/4502&”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:8] Set(“SIP/4501-00000009”, “ITER2=2”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/4501-00000009”, “0?begin2”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:10] Set(“SIP/4501-00000009”, “THISDIAL=SIP/4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:11] Return(“SIP/4501-00000009”, “”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:9] Set(“SIP/4501-00000009”, “DSTRING=SIP/4502&”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]one:10] Set(“SIP/4501-00000009”, “ITER=2”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/4501-00000009”, “0?begin”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:12] Set(“SIP/4501-00000009”, “DSTRING=SIP/4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:13] Return(“SIP/4501-00000009”, “”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:27] GotoIf(“SIP/4501-00000009”, “0?nodial”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:28] GotoIf(“SIP/4501-00000009”, “1?skiptrace”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Goto (macro-dial-one,s,30)
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:30] Set(“SIP/4501-00000009”, “D_OPTIONS=tr”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:31] ExecIf(“SIP/4501-00000009”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:32] ExecIf(“SIP/4501-00000009”, “0?SIPAddHeader()”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:33] ExecIf(“SIP/4501-00000009”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:34] GosubIf(“SIP/4501-00000009”, “0?qwait,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:35] Set(“SIP/4501-00000009”, “__CWIGNORE=”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:36] Set(“SIP/4501-00000009”, “__KEEPCID=TRUE”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:37] GotoIf(“SIP/4501-00000009”, “0?usegoto,1”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:38] GotoIf(“SIP/4501-00000009”, “0?godial”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:39] Set(“SIP/4501-00000009”, “CONNECTEDLINE(name,i)=Jacqui Downing”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:40] Set(“SIP/4501-00000009”, “CONNECTEDLINE(num)=4502”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:41] Set(“SIP/4501-00000009”, “D_OPTIONS=trI”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] pbx.c: – Executing [[email protected]:42] Dial(“SIP/4501-00000009”, “SIP/4502,trI”) in new stack
[2011-12-01 18:53:05] VERBOSE[3503] netsock2.c: == Using SIP RTP TOS bits 184
[2011-12-01 18:53:05] VERBOSE[3503] netsock2.c: == Using SIP RTP CoS mark 5
[2011-12-01 18:53:05] VERBOSE[3503] app_dial.c: – Called SIP/4502
[2011-12-01 18:53:05] VERBOSE[3503] app_dial.c: – Connected line update to SIP/4501-00000009 prevented.
[2011-12-01 18:53:37] VERBOSE[3503] app_dial.c: – SIP/4502-0000000a is circuit-busy
[2011-12-01 18:53:37] VERBOSE[3503] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:43] ExecIf(“SIP/4501-00000009”, “0?Set(DIALSTATUS=)”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:44] GosubIf(“SIP/4501-00000009”, “0?s-CONGESTION,1”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:45] MacroExit(“SIP/4501-00000009”, “”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/4501-00000009”, “0?exit”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:16] Set(“SIP/4501-00000009”, “SV_DIALSTATUS=CONGESTION”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:17] GosubIf(“SIP/4501-00000009”, “0?docfu,1”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:18] GosubIf(“SIP/4501-00000009”, “0?docfb,1”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:19] Set(“SIP/4501-00000009”, “DIALSTATUS=CONGESTION”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:20] ExecIf(“SIP/4501-00000009”, “0?MacroExit()”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/4501-00000009”, “1?s-CONGESTION,1”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Goto (macro-exten-vm,s-CONGESTION,1)
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/4501-00000009”, “0?exit,1”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:2] PlayTones(“SIP/4501-00000009”, “congestion”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:3] Congestion(“SIP/4501-00000009”, “10”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] app_macro.c: == Spawn extension (macro-exten-vm, s-CONGESTION, 3) exited non-zero on ‘SIP/4501-00000009’ in macro ‘exten-vm’
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: == Spawn extension (from-internal, 4502, 2) exited non-zero on ‘SIP/4501-00000009’
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/4501-00000009”, “”) in new stack
[2011-12-01 18:53:37] VERBOSE[3503] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/4501-00000009’