Hello,
I’ve just installed the latest stable FreePBX Distro, configured Asterisk SIP Settings (my local network is under NAT) and created 2 SIP extensions (100 and 101).
When these extensions are setup in Zoiper (free version), they both register but give 503 Service Unavailable error when trying to call each other.
With XLite (again free version) everything works great.
Nothing else is changed in FreePBX and softphones. I get following logs when trying to call from Zoiper:
[2014-03-18 16:22:02] VERBOSE[1809][C-0000001c] netsock2.c: == Using SIP RTP CoS mark 5
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "__RINGTIMER=15") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Macro("SIP/100-0000001f", "exten-vm,novm,101,0,0,0") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]o-exten-vm:1] Macro("SIP/100-0000001f", "user-callerid,") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "TOUCH_MONITOR=1395145322.31") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "AMPUSER=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/100-0000001f", "0?report") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/100-0000001f", "1?Set(REALCALLERIDNUM=100)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] Set("SIP/100-0000001f", "AMPUSER=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/100-0000001f", "0?limit") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:7] Set("SIP/100-0000001f", "AMPUSERCIDNAME=Arman Poghosyan") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/100-0000001f", "0?report") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:9] Set("SIP/100-0000001f", "AMPUSERCID=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:10] Set("SIP/100-0000001f", "__DIAL_OPTIONS=Ttr") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:11] Set("SIP/100-0000001f", "CALLERID(all)="Arman Poghosyan" <100>") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/100-0000001f", "0?limit") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:13] ExecIf("SIP/100-0000001f", "0?Set(GROUP(concurrency_limit)=100)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:14] GosubIf("SIP/100-0000001f", "7?sub-ccss,s,1(macro-exten-vm,101)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "CCSS_SETUP=TRUE") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] GosubIf("SIP/100-0000001f", "0?monitor_config,1(macro-exten-vm,101):monitor_default,1(macro-exten-vm,101)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/100-0000001f", "1?is_exten") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (sub-ccss,monitor_default,4)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] Set("SIP/100-0000001f", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] Set("SIP/100-0000001f", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:6] Return("SIP/100-0000001f", "TRUE") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] GosubIf("SIP/100-0000001f", "7?agent_config,1():agent_default,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] Set("SIP/100-0000001f", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] Set("SIP/100-0000001f", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] Set("SIP/100-0000001f", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2014-03-18 16:22:02] WARNING[4254][C-0000001c] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:6] ExecIf("SIP/100-0000001f", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:7] ExecIf("SIP/100-0000001f", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:8] ExecIf("SIP/100-0000001f", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:9] Set("SIP/100-0000001f", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2014-03-18 16:22:02] WARNING[4254][C-0000001c] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:10] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] Set("SIP/100-0000001f", "DB(AMPUSER/100/ccss/last_number)=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:6] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:15] ExecIf("SIP/100-0000001f", "0?Set(CHANNEL(language)=)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:16] GotoIf("SIP/100-0000001f", "0?continue") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:17] Set("SIP/100-0000001f", "__TTL=64") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:18] GotoIf("SIP/100-0000001f", "1?continue") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (macro-user-callerid,s,29)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:29] Set("SIP/100-0000001f", "CALLERID(number)=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:30] Set("SIP/100-0000001f", "CALLERID(name)=Arman Poghosyan") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:31] Set("SIP/100-0000001f", "CDR(cnum)=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:32] Set("SIP/100-0000001f", "CDR(cnam)=Arman Poghosyan") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:33] Set("SIP/100-0000001f", "CHANNEL(language)=en") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "RingGroupMethod=none") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] Set("SIP/100-0000001f", "__EXTTOCALL=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] Set("SIP/100-0000001f", "__PICKUPMARK=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] Set("SIP/100-0000001f", "RT=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:6] ExecIf("SIP/100-0000001f", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:7] ExecIf("SIP/100-0000001f", "0?MacroExit()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:8] Gosub("SIP/100-0000001f", "sub-record-check,s,1(exten,101,)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "REC_POLICY_MODE_SAVE=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/100-0000001f", "1?check") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (sub-record-check,s,7)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:7] Set("SIP/100-0000001f", "__MON_FMT=wav") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/100-0000001f", "1?next") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (sub-record-check,s,11)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:11] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:12] ExecIf("SIP/100-0000001f", "0?Set(__REC_POLICY_MODE=)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:13] GotoIf("SIP/100-0000001f", "0?exten,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:14] Set("SIP/100-0000001f", "__REC_STATUS=INITIALIZED") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:15] Set("SIP/100-0000001f", "NOW=1395145322") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:16] Set("SIP/100-0000001f", "__DAY=18") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:17] Set("SIP/100-0000001f", "__MONTH=03") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:18] Set("SIP/100-0000001f", "__YEAR=2014") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:19] Set("SIP/100-0000001f", "__TIMESTR=20140318-162202") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:20] Set("SIP/100-0000001f", "__FROMEXTEN=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:21] Set("SIP/100-0000001f", "__CALLFILENAME=exten-101-100-20140318-162202-1395145322.31") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:22] Goto("SIP/100-0000001f", "exten,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (sub-record-check,exten,1)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/100-0000001f", "0?callee") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "__REC_POLICY_MODE=dontcare") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/100-0000001f", "1?caller") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (sub-record-check,exten,10)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:10] Set("SIP/100-0000001f", "__REC_POLICY_MODE=dontcare") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:11] GosubIf("SIP/100-0000001f", "0?record,1(exten,101,100)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:12] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/100-0000001f", "1?macrodial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (macro-exten-vm,s,15)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:15] GosubIf("SIP/100-0000001f", "0?clrheader,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:16] Macro("SIP/100-0000001f", "dial-one,,Ttr,101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "DEXTEN=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "DIALSTATUS_CW=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] GosubIf("SIP/100-0000001f", "0?screen,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] GosubIf("SIP/100-0000001f", "0?cf,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/100-0000001f", "1?skip1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (macro-dial-one,s,8)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/100-0000001f", "0?nodial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/100-0000001f", "0?continue") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:10] Set("SIP/100-0000001f", "EXTHASCW=ENABLED") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/100-0000001f", "0?next1:cwinusebusy") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (macro-dial-one,s,23)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:23] GotoIf("SIP/100-0000001f", "1?next3:continue") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (macro-dial-one,s,24)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:24] ExecIf("SIP/100-0000001f", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:25] GotoIf("SIP/100-0000001f", "0?nodial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:26] GosubIf("SIP/100-0000001f", "1?dstring,1():dlocal,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "DSTRING=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "DEVICES=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/100-0000001f", "0?Set(DEVICES=01)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] Set("SIP/100-0000001f", "LOOPCNT=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:6] Set("SIP/100-0000001f", "ITER=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:7] Set("SIP/100-0000001f", "THISDIAL=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:8] GosubIf("SIP/100-0000001f", "1?zap2dahdi,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Set("SIP/100-0000001f", "NEWDIAL=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] Set("SIP/100-0000001f", "LOOPCNT2=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:4] Set("SIP/100-0000001f", "ITER2=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:5] Set("SIP/100-0000001f", "THISPART2=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:6] ExecIf("SIP/100-0000001f", "0?Set(THISPART2=DAHDI/101)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:7] Set("SIP/100-0000001f", "NEWDIAL=SIP/101&") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:8] Set("SIP/100-0000001f", "ITER2=2") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/100-0000001f", "0?begin2") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:10] Set("SIP/100-0000001f", "THISDIAL=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:11] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:9] Set("SIP/100-0000001f", "DSTRING=SIP/101&") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:10] Set("SIP/100-0000001f", "ITER=2") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/100-0000001f", "0?begin") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:12] Set("SIP/100-0000001f", "DSTRING=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:13] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:27] GotoIf("SIP/100-0000001f", "0?nodial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:28] GotoIf("SIP/100-0000001f", "0?skiptrace") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:29] GosubIf("SIP/100-0000001f", "1?ctset,1():ctclear,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "DB(CALLTRACE/101)=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:30] Set("SIP/100-0000001f", "D_OPTIONS=Ttr") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:31] ExecIf("SIP/100-0000001f", "0?SIPAddHeader(Alert-Info: )") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:32] ExecIf("SIP/100-0000001f", "0?SIPAddHeader()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:33] ExecIf("SIP/100-0000001f", "0?Set(CHANNEL(musicclass)=)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:34] GosubIf("SIP/100-0000001f", "0?qwait,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:35] Set("SIP/100-0000001f", "__CWIGNORE=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:36] Set("SIP/100-0000001f", "__KEEPCID=TRUE") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:37] GotoIf("SIP/100-0000001f", "0?usegoto,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:38] GotoIf("SIP/100-0000001f", "0?godial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:39] Gosub("SIP/100-0000001f", "sub-presencestate-display,s,1(101)") in new stack
[2014-03-18 16:22:02] WARNING[4254][C-0000001c] func_presencestate.c: PRESENCE_STATE unknown
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Goto("SIP/100-0000001f", "state-,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (sub-presencestate-display,state-,1)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Set("SIP/100-0000001f", "PRESENCESTATE_DISPLAY=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:40] Set("SIP/100-0000001f", "CONNECTEDLINE(name,i)=TEST") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:41] Set("SIP/100-0000001f", "CONNECTEDLINE(num)=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:42] Set("SIP/100-0000001f", "D_OPTIONS=TtrI") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:43] Dial("SIP/100-0000001f", "SIP/101,,TtrI") in new stack
[2014-03-18 16:22:02] WARNING[4254][C-0000001c] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:44] ExecIf("SIP/100-0000001f", "0?MacroExit()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:45] ExecIf("SIP/100-0000001f", "0?Set(DIALSTATUS=)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:46] GosubIf("SIP/100-0000001f", "0?s-CHANUNAVAIL,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:47] MacroExit("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:17] Set("SIP/100-0000001f", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:18] GosubIf("SIP/100-0000001f", "0?docfu,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:19] GosubIf("SIP/100-0000001f", "0?docfb,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:20] Set("SIP/100-0000001f", "DIALSTATUS=CHANUNAVAIL") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:21] ExecIf("SIP/100-0000001f", "0?MacroExit()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:22] GotoIf("SIP/100-0000001f", "1?s-CHANUNAVAIL,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Goto (macro-exten-vm,s-CHANUNAVAIL,1)
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/100-0000001f", "0?exit,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:2] PlayTones("SIP/100-0000001f", "congestion") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:3] Congestion("SIP/100-0000001f", "10") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] app_macro.c: == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on 'SIP/100-0000001f' in macro 'exten-vm'
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: == Spawn extension (from-internal, 101, 2) exited non-zero on 'SIP/100-0000001f'
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [[email protected]:1] Hangup("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/100-0000001f'
Can anybody help me to find the problem? I really need Zoiper softphone as it allows to register multiple accounts in free version and we’re already using it with one provider.