Zoiper call issues

armpogart · March 18, 2014, 12:24pm

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 [101@from-internal:1] Set("SIP/100-0000001f", "__RINGTIMER=15") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [101@from-internal: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 [s@macro-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 [s@macro-user-callerid: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 [s@macro-user-callerid:2] Set("SIP/100-0000001f", "AMPUSER=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/100-0000001f", "0?report") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:5] Set("SIP/100-0000001f", "AMPUSER=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/100-0000001f", "0?limit") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/100-0000001f", "AMPUSERCIDNAME=Arman Poghosyan") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/100-0000001f", "0?report") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/100-0000001f", "AMPUSERCID=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/100-0000001f", "__DIAL_OPTIONS=Ttr") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:12] GotoIf("SIP/100-0000001f", "0?limit") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@sub-ccss:1] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/100-0000001f", "CCSS_SETUP=TRUE") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-ccss: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 [monitor_default@sub-ccss: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 [monitor_default@sub-ccss: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 [monitor_default@sub-ccss: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 [monitor_default@sub-ccss:6] Return("SIP/100-0000001f", "TRUE") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-ccss: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 [agent_config@sub-ccss: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 [agent_config@sub-ccss: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 [agent_config@sub-ccss: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 [agent_config@sub-ccss: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 [agent_config@sub-ccss: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 [agent_config@sub-ccss: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 [agent_config@sub-ccss: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 [agent_config@sub-ccss:8] ExecIf("SIP/100-0000001f", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/100_101@from-ccss-)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [agent_config@sub-ccss: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 [agent_config@sub-ccss:10] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-ccss: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 [s@sub-ccss:6] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:16] GotoIf("SIP/100-0000001f", "0?continue") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid:17] Set("SIP/100-0000001f", "__TTL=64") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:29] Set("SIP/100-0000001f", "CALLERID(number)=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:31] Set("SIP/100-0000001f", "CDR(cnum)=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-user-callerid: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 [s@macro-user-callerid:33] Set("SIP/100-0000001f", "CHANNEL(language)=en") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/100-0000001f", "RingGroupMethod=none") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/100-0000001f", "__EXTTOCALL=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/100-0000001f", "__PICKUPMARK=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/100-0000001f", "RT=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm: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 [s@macro-exten-vm:7] ExecIf("SIP/100-0000001f", "0?MacroExit()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check:7] Set("SIP/100-0000001f", "__MON_FMT=wav") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check: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 [s@sub-record-check:11] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check: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 [s@sub-record-check:13] GotoIf("SIP/100-0000001f", "0?exten,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/100-0000001f", "__REC_STATUS=INITIALIZED") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/100-0000001f", "NOW=1395145322") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/100-0000001f", "__DAY=18") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/100-0000001f", "__MONTH=03") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/100-0000001f", "__YEAR=2014") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/100-0000001f", "__TIMESTR=20140318-162202") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/100-0000001f", "__FROMEXTEN=100") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@sub-record-check: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 [s@sub-record-check: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 [exten@sub-record-check:1] GotoIf("SIP/100-0000001f", "0?callee") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [exten@sub-record-check: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 [exten@sub-record-check: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 [exten@sub-record-check: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 [exten@sub-record-check: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 [exten@sub-record-check:12] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm: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 [s@macro-exten-vm:15] GosubIf("SIP/100-0000001f", "0?clrheader,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm: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 [s@macro-dial-one:1] Set("SIP/100-0000001f", "DEXTEN=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:2] Set("SIP/100-0000001f", "DIALSTATUS_CW=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("SIP/100-0000001f", "0?screen,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("SIP/100-0000001f", "0?cf,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@macro-dial-one:8] GotoIf("SIP/100-0000001f", "0?nodial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("SIP/100-0000001f", "0?continue") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:10] Set("SIP/100-0000001f", "EXTHASCW=ENABLED") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@macro-dial-one: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 [s@macro-dial-one: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 [s@macro-dial-one:25] GotoIf("SIP/100-0000001f", "0?nodial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [dstring@macro-dial-one:1] Set("SIP/100-0000001f", "DSTRING=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("SIP/100-0000001f", "DEVICES=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one: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 [dstring@macro-dial-one:5] Set("SIP/100-0000001f", "LOOPCNT=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("SIP/100-0000001f", "ITER=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("SIP/100-0000001f", "THISDIAL=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/100-0000001f", "1?zap2dahdi,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/100-0000001f", "0?Return()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/100-0000001f", "NEWDIAL=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/100-0000001f", "LOOPCNT2=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/100-0000001f", "ITER2=1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/100-0000001f", "THISPART2=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one: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 [zap2dahdi@macro-dial-one:7] Set("SIP/100-0000001f", "NEWDIAL=SIP/101&") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/100-0000001f", "ITER2=2") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/100-0000001f", "0?begin2") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/100-0000001f", "THISDIAL=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("SIP/100-0000001f", "DSTRING=SIP/101&") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("SIP/100-0000001f", "ITER=2") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/100-0000001f", "0?begin") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("SIP/100-0000001f", "DSTRING=SIP/101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("SIP/100-0000001f", "0?nodial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("SIP/100-0000001f", "0?skiptrace") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [ctset@macro-dial-one: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 [ctset@macro-dial-one:2] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:30] Set("SIP/100-0000001f", "D_OPTIONS=Ttr") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@macro-dial-one:32] ExecIf("SIP/100-0000001f", "0?SIPAddHeader()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@macro-dial-one:34] GosubIf("SIP/100-0000001f", "0?qwait,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:35] Set("SIP/100-0000001f", "__CWIGNORE=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:36] Set("SIP/100-0000001f", "__KEEPCID=TRUE") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("SIP/100-0000001f", "0?usegoto,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("SIP/100-0000001f", "0?godial") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@sub-presencestate-display: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 [state-@sub-presencestate-display:1] Set("SIP/100-0000001f", "PRESENCESTATE_DISPLAY=") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [state-@sub-presencestate-display:2] Return("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@macro-dial-one:41] Set("SIP/100-0000001f", "CONNECTEDLINE(num)=101") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:42] Set("SIP/100-0000001f", "D_OPTIONS=TtrI") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@macro-dial-one:44] ExecIf("SIP/100-0000001f", "0?MacroExit()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one:45] ExecIf("SIP/100-0000001f", "0?Set(DIALSTATUS=)") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-dial-one: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 [s@macro-dial-one:47] MacroExit("SIP/100-0000001f", "") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:17] Set("SIP/100-0000001f", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:18] GosubIf("SIP/100-0000001f", "0?docfu,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:19] GosubIf("SIP/100-0000001f", "0?docfb,1()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:20] Set("SIP/100-0000001f", "DIALSTATUS=CHANUNAVAIL") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm:21] ExecIf("SIP/100-0000001f", "0?MacroExit()") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s@macro-exten-vm: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 [s-CHANUNAVAIL@macro-exten-vm:1] GotoIf("SIP/100-0000001f", "0?exit,1") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s-CHANUNAVAIL@macro-exten-vm:2] PlayTones("SIP/100-0000001f", "congestion") in new stack
[2014-03-18 16:22:02] VERBOSE[4254][C-0000001c] pbx.c: -- Executing [s-CHANUNAVAIL@macro-exten-vm: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 [h@from-internal: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.

armpogart · March 18, 2014, 12:49pm

When registering one extension with Zoiper and second one with XLite, I have noticed that I can call from Zoiper to XLite, but I can’t call from XLite to Zoiper.

jfinstrom · March 18, 2014, 5:44pm

What does “sip show peers” show?

armpogart · March 19, 2014, 10:03am

I can’t say exactly what was output, cause zoiper started working as soon as I changed “Nat” to “yes” for each extension. But now I have another problem. Remote extensions don’t work properly until I place call to them. I mean:

I have one internal network extension. It’s showing registered and working properly. I have 2 other remote extensions. As soon as I oper Zoiper, it shows that extensions are registered, but they can’t call (503 - Service Unavailable), until the moment I first call to them from working internal extension. As soon as I call from internal extension, they get something like “activated”. Any help is appreciated.

sip show peers output now is:
Name/username Host Forcerport Comedia Port Status 100/100 192.168.10.101 Yes Yes 35968 OK(9 ms) 301 (Unspecified) Yes Yes 0 UNKNOWN 302 (Unspecified) Yes Yes 0 UNKNOWN

Extension 100 is local network extension. Extensions 301 and 302 are remote. As soon as I call them first, their ips ports and status are starting to show.