Can place outgoing calls, can't receive calls

tamiral · May 15, 2014, 8:15pm

I’m currently dealing with a very peculiar issue on my hosted PBX running the latest FreePBX distro. Everything’s working fine so far and I’m getting quite familiar with the system. I’m using 3 Cisco SPA-303 phones to connect to my FreePBX on a standard connection, but two of these phones can place outgoing calls without an issue, but when I try to call them internally, I get a “The person on that extension is unavailable”. I’ve logged in to the asterisk console and have copied the call process on both scenarios: the first one tracks an incoming call on the phone that WORKS; the second traces a call on the phone that REFUSES to receive calls. One interesting point that might be of help: when I log into one of the problematic phones (that won’t receive calls), and re-initiate the connection to the PBX server (let’s say change password or any other configuration that requires a reboot and re-registration), that phone will receive calls for a minute or two, but then will go back to the same state. Here are the logs:

In both cases the outgoing call is being performed from extension 110 (soft phone) to ext. 103 (works) and ext. 102 (refuses).

THE PHONE THAT RECEIVES CALLS:
== Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 – Executing [103@from-internal:1] GotoIf(“SIP/110-000001d2”, “1?ext-local,103,1”) in new stack – Goto (ext-local,103,1) – Executing [103@ext-local:1] Set(“SIP/110-000001d2”, “__RINGTIMER=15”) in new stack – Executing [103@ext-local:2] Macro(“SIP/110-000001d2”, “exten-vm,novm,103,0,0,0”) in new stack – Executing [s@macro-exten-vm:1] Macro(“SIP/110-000001d2”, “user-callerid,”) in new stack – Executing [s@macro-user-callerid:1] Set(“SIP/110-000001d2”, “TOUCH_MONITOR=1400184767.466”) in new stack – Executing [s@macro-user-callerid:2] Set(“SIP/110-000001d2”, “AMPUSER=110”) in new stack – Executing [s@macro-user-callerid:3] GotoIf(“SIP/110-000001d2”, “0?report”) in new stack – Executing [s@macro-user-callerid:4] ExecIf(“SIP/110-000001d2”, “1?Set(REALCALLERIDNUM=110)”) in new stack – Executing [s@macro-user-callerid:5] Set(“SIP/110-000001d2”, “AMPUSER=110”) in new stack – Executing [s@macro-user-callerid:6] GotoIf(“SIP/110-000001d2”, “0?limit”) in new stack – Executing [s@macro-user-callerid:7] Set(“SIP/110-000001d2”, “AMPUSERCIDNAME=Conference Room”) in new stack – Executing [s@macro-user-callerid:8] GotoIf(“SIP/110-000001d2”, “0?report”) in new stack – Executing [s@macro-user-callerid:9] Set(“SIP/110-000001d2”, “AMPUSERCID=110”) in new stack – Executing [s@macro-user-callerid:10] Set(“SIP/110-000001d2”, “__DIAL_OPTIONS=Ttr”) in new stack – Executing [s@macro-user-callerid:11] Set(“SIP/110-000001d2”, “CALLERID(all)=“Conference Room” <110>”) in new stack – Executing [s@macro-user-callerid:12] GotoIf(“SIP/110-000001d2”, “0?limit”) in new stack – Executing [s@macro-user-callerid:13] ExecIf(“SIP/110-000001d2”, “0?Set(GROUP(concurrency_limit)=110)”) in new stack – Executing [s@macro-user-callerid:14] ExecIf(“SIP/110-000001d2”, “0?Set(CHANNEL(language)=)”) in new stack – Executing [s@macro-user-callerid:15] GosubIf(“SIP/110-000001d2”, “7?sub-ccss,s,1(macro-exten-vm,103)”) in new stack – Executing [s@sub-ccss:1] ExecIf(“SIP/110-000001d2”, “0?Return()”) in new stack – Executing [s@sub-ccss:2] Set(“SIP/110-000001d2”, “CCSS_SETUP=TRUE”) in new stack – Executing [s@sub-ccss:3] GosubIf(“SIP/110-000001d2”, “0?monitor_config,1(macro-exten-vm,103):monitor_default,1(macro-exten-vm,103)”) in new stack – Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/110-000001d2”, “1?is_exten”) in new stack – Goto (sub-ccss,monitor_default,4) – Executing [monitor_default@sub-ccss:4] Set(“SIP/110-000001d2”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack – Executing [monitor_default@sub-ccss:5] Set(“SIP/110-000001d2”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack – Executing [monitor_default@sub-ccss:6] Return(“SIP/110-000001d2”, “TRUE”) in new stack – Executing [s@sub-ccss:4] GosubIf(“SIP/110-000001d2”, “7?agent_config,1():agent_default,1()”) in new stack – Executing [agent_config@sub-ccss:1] Set(“SIP/110-000001d2”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack – Executing [agent_config@sub-ccss:2] Set(“SIP/110-000001d2”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack – Executing [agent_config@sub-ccss:3] Set(“SIP/110-000001d2”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack – Executing [agent_config@sub-ccss:4] Set(“SIP/110-000001d2”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack – Executing [agent_config@sub-ccss:5] Set(“SIP/110-000001d2”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack – Executing [agent_config@sub-ccss:6] ExecIf(“SIP/110-000001d2”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack – Executing [agent_config@sub-ccss:7] ExecIf(“SIP/110-000001d2”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack – Executing [agent_config@sub-ccss:8] ExecIf(“SIP/110-000001d2”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/110_103@from-ccss-)”) in new stack – Executing [agent_config@sub-ccss:9] Set(“SIP/110-000001d2”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack – Executing [agent_config@sub-ccss:10] Return(“SIP/110-000001d2”, “”) in new stack – Executing [s@sub-ccss:5] Set(“SIP/110-000001d2”, “DB(AMPUSER/110/ccss/last_number)=103”) in new stack – Executing [s@sub-ccss:6] Return(“SIP/110-000001d2”, “”) in new stack – Executing [s@macro-user-callerid:16] GotoIf(“SIP/110-000001d2”, “0?continue”) in new stack – Executing [s@macro-user-callerid:17] Set(“SIP/110-000001d2”, “__TTL=64”) in new stack – Executing [s@macro-user-callerid:18] GotoIf(“SIP/110-000001d2”, “1?continue”) in new stack – Goto (macro-user-callerid,s,29) – Executing [s@macro-user-callerid:29] Set(“SIP/110-000001d2”, “CALLERID(number)=110”) in new stack – Executing [s@macro-user-callerid:30] Set(“SIP/110-000001d2”, “CALLERID(name)=Conference Room”) in new stack – Executing [s@macro-user-callerid:31] Set(“SIP/110-000001d2”, “CDR(cnum)=110”) in new stack – Executing [s@macro-user-callerid:32] Set(“SIP/110-000001d2”, “CDR(cnam)=Conference Room”) in new stack – Executing [s@macro-user-callerid:33] Set(“SIP/110-000001d2”, “CHANNEL(language)=en”) in new stack – Executing [s@macro-exten-vm:2] Set(“SIP/110-000001d2”, “RingGroupMethod=none”) in new stack – Executing [s@macro-exten-vm:3] Set(“SIP/110-000001d2”, “__EXTTOCALL=103”) in new stack – Executing [s@macro-exten-vm:4] Set(“SIP/110-000001d2”, “__PICKUPMARK=103”) in new stack – Executing [s@macro-exten-vm:5] Set(“SIP/110-000001d2”, “RT=”) in new stack – Executing [s@macro-exten-vm:6] ExecIf(“SIP/110-000001d2”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack – Executing [s@macro-exten-vm:7] ExecIf(“SIP/110-000001d2”, “0?MacroExit()”) in new stack – Executing [s@macro-exten-vm:8] Gosub(“SIP/110-000001d2”, “sub-record-check,s,1(exten,103,)”) in new stack – Executing [s@sub-record-check:1] Set(“SIP/110-000001d2”, “REC_POLICY_MODE_SAVE=”) in new stack – Executing [s@sub-record-check:2] GotoIf(“SIP/110-000001d2”, “1?check”) in new stack – Goto (sub-record-check,s,7) – Executing [s@sub-record-check:7] Set(“SIP/110-000001d2”, “__MON_FMT=wav”) in new stack – Executing [s@sub-record-check:8] GotoIf(“SIP/110-000001d2”, “1?next”) in new stack – Goto (sub-record-check,s,11) – Executing [s@sub-record-check:11] ExecIf(“SIP/110-000001d2”, “0?Return()”) in new stack – Executing [s@sub-record-check:12] ExecIf(“SIP/110-000001d2”, “0?Set(__REC_POLICY_MODE=)”) in new stack – Executing [s@sub-record-check:13] GotoIf(“SIP/110-000001d2”, “0?exten,1”) in new stack – Executing [s@sub-record-check:14] Set(“SIP/110-000001d2”, “__REC_STATUS=INITIALIZED”) in new stack – Executing [s@sub-record-check:15] Set(“SIP/110-000001d2”, “NOW=1400184767”) in new stack – Executing [s@sub-record-check:16] Set(“SIP/110-000001d2”, “__DAY=15”) in new stack – Executing [s@sub-record-check:17] Set(“SIP/110-000001d2”, “__MONTH=05”) in new stack – Executing [s@sub-record-check:18] Set(“SIP/110-000001d2”, “__YEAR=2014”) in new stack – Executing [s@sub-record-check:19] Set(“SIP/110-000001d2”, “__TIMESTR=20140515-161247”) in new stack – Executing [s@sub-record-check:20] Set(“SIP/110-000001d2”, “__FROMEXTEN=110”) in new stack – Executing [s@sub-record-check:21] Set(“SIP/110-000001d2”, “__CALLFILENAME=exten-103-110-20140515-161247-1400184767.466”) in new stack – Executing [s@sub-record-check:22] Goto(“SIP/110-000001d2”, “exten,1”) in new stack – Goto (sub-record-check,exten,1) – Executing [exten@sub-record-check:1] GotoIf(“SIP/110-000001d2”, “0?callee”) in new stack – Executing [exten@sub-record-check:2] Set(“SIP/110-000001d2”, “__REC_POLICY_MODE=dontcare”) in new stack – Executing [exten@sub-record-check:3] GotoIf(“SIP/110-000001d2”, “1?caller”) in new stack – Goto (sub-record-check,exten,10) – Executing [exten@sub-record-check:10] Set(“SIP/110-000001d2”, “__REC_POLICY_MODE=dontcare”) in new stack – Executing [exten@sub-record-check:11] GosubIf(“SIP/110-000001d2”, “0?record,1(exten,103,110)”) in new stack – Executing [exten@sub-record-check:12] Return(“SIP/110-000001d2”, “”) in new stack – Executing [s@macro-exten-vm:9] GotoIf(“SIP/110-000001d2”, “1?macrodial”) in new stack – Goto (macro-exten-vm,s,15) – Executing [s@macro-exten-vm:15] GosubIf(“SIP/110-000001d2”, “0?clrheader,1()”) in new stack – Executing [s@macro-exten-vm:16] Macro(“SIP/110-000001d2”, “dial-one,Ttr,103”) in new stack – Executing [s@macro-dial-one:1] Set(“SIP/110-000001d2”, “DEXTEN=103”) in new stack – Executing [s@macro-dial-one:2] Set(“SIP/110-000001d2”, “DIALSTATUS_CW=”) in new stack – Executing [s@macro-dial-one:3] GosubIf(“SIP/110-000001d2”, “0?screen,1()”) in new stack – Executing [s@macro-dial-one:4] GosubIf(“SIP/110-000001d2”, “0?cf,1()”) in new stack – Executing [s@macro-dial-one:5] GotoIf(“SIP/110-000001d2”, “1?skip1”) in new stack – Goto (macro-dial-one,s,8) – Executing [s@macro-dial-one:8] GotoIf(“SIP/110-000001d2”, “0?nodial”) in new stack – Executing [s@macro-dial-one:9] GotoIf(“SIP/110-000001d2”, “0?continue”) in new stack – Executing [s@macro-dial-one:10] Set(“SIP/110-000001d2”, “EXTHASCW=ENABLED”) in new stack – Executing [s@macro-dial-one:11] GotoIf(“SIP/110-000001d2”, “0?next1:cwinusebusy”) in new stack – Goto (macro-dial-one,s,23) – Executing [s@macro-dial-one:23] GotoIf(“SIP/110-000001d2”, “1?next3:continue”) in new stack – Goto (macro-dial-one,s,24) – Executing [s@macro-dial-one:24] ExecIf(“SIP/110-000001d2”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack – Executing [s@macro-dial-one:25] GotoIf(“SIP/110-000001d2”, “0?nodial”) in new stack – Executing [s@macro-dial-one:26] GosubIf(“SIP/110-000001d2”, “1?dstring,1():dlocal,1()”) in new stack – Executing [dstring@macro-dial-one:1] Set(“SIP/110-000001d2”, “DSTRING=”) in new stack – Executing [dstring@macro-dial-one:2] Set(“SIP/110-000001d2”, “DEVICES=103”) in new stack – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/110-000001d2”, “0?Return()”) in new stack – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/110-000001d2”, “0?Set(DEVICES=03)”) in new stack – Executing [dstring@macro-dial-one:5] Set(“SIP/110-000001d2”, “LOOPCNT=1”) in new stack – Executing [dstring@macro-dial-one:6] Set(“SIP/110-000001d2”, “ITER=1”) in new stack – Executing [dstring@macro-dial-one:7] Set(“SIP/110-000001d2”, “THISDIAL=SIP/103”) in new stack – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/110-000001d2”, “1?zap2dahdi,1()”) in new stack – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/110-000001d2”, “0?Return()”) in new stack – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/110-000001d2”, “NEWDIAL=”) in new stack – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/110-000001d2”, “LOOPCNT2=1”) in new stack – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/110-000001d2”, “ITER2=1”) in new stack – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/110-000001d2”, “THISPART2=SIP/103”) in new stack – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/110-000001d2”, “0?Set(THISPART2=DAHDI/103)”) in new stack – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/110-000001d2”, “NEWDIAL=SIP/103&”) in new stack – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/110-000001d2”, “ITER2=2”) in new stack – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/110-000001d2”, “0?begin2”) in new stack – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/110-000001d2”, “THISDIAL=SIP/103”) in new stack – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/110-000001d2”, “”) in new stack – Executing [dstring@macro-dial-one:9] Set(“SIP/110-000001d2”, “DSTRING=SIP/103&”) in new stack – Executing [dstring@macro-dial-one:10] Set(“SIP/110-000001d2”, “ITER=2”) in new stack – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/110-000001d2”, “0?begin”) in new stack – Executing [dstring@macro-dial-one:12] Set(“SIP/110-000001d2”, “DSTRING=SIP/103”) in new stack – Executing [dstring@macro-dial-one:13] Return(“SIP/110-000001d2”, “”) in new stack – Executing [s@macro-dial-one:27] GotoIf(“SIP/110-000001d2”, “0?nodial”) in new stack – Executing [s@macro-dial-one:28] GotoIf(“SIP/110-000001d2”, “0?skiptrace”) in new stack – Executing [s@macro-dial-one:29] GosubIf(“SIP/110-000001d2”, “1?ctset,1():ctclear,1()”) in new stack – Executing [ctset@macro-dial-one:1] Set(“SIP/110-000001d2”, “DB(CALLTRACE/103)=110”) in new stack – Executing [ctset@macro-dial-one:2] Return(“SIP/110-000001d2”, “”) in new stack – Executing [s@macro-dial-one:30] Set(“SIP/110-000001d2”, “D_OPTIONS=Ttr”) in new stack – Executing [s@macro-dial-one:31] ExecIf(“SIP/110-000001d2”, “0?SIPAddHeader(Alert-Info: )”) in new stack – Executing [s@macro-dial-one:32] ExecIf(“SIP/110-000001d2”, “0?SIPAddHeader()”) in new stack – Executing [s@macro-dial-one:33] ExecIf(“SIP/110-000001d2”, “0?Set(CHANNEL(musicclass)=)”) in new stack – Executing [s@macro-dial-one:34] GosubIf(“SIP/110-000001d2”, “0?qwait,1()”) in new stack – Executing [s@macro-dial-one:35] Set(“SIP/110-000001d2”, “__CWIGNORE=”) in new stack – Executing [s@macro-dial-one:36] Set(“SIP/110-000001d2”, “__KEEPCID=TRUE”) in new stack – Executing [s@macro-dial-one:37] GotoIf(“SIP/110-000001d2”, “0?usegoto,1”) in new stack – Executing [s@macro-dial-one:38] GotoIf(“SIP/110-000001d2”, “0?godial”) in new stack – Executing [s@macro-dial-one:39] Gosub(“SIP/110-000001d2”, “sub-presencestate-display,s,1(103)”) in new stack – Executing [s@sub-presencestate-display:1] Goto(“SIP/110-000001d2”, “state-,1”) in new stack – Goto (sub-presencestate-display,state-,1) – Executing [state-@sub-presencestate-display:1] Set(“SIP/110-000001d2”, “PRESENCESTATE_DISPLAY=”) in new stack – Executing [state-@sub-presencestate-display:2] Return(“SIP/110-000001d2”, “”) in new stack – Executing [s@macro-dial-one:40] Set(“SIP/110-000001d2”, “CONNECTEDLINE(name,i)=Tamir Alush”) in new stack – Executing [s@macro-dial-one:41] Set(“SIP/110-000001d2”, “CONNECTEDLINE(num)=103”) in new stack – Executing [s@macro-dial-one:42] Set(“SIP/110-000001d2”, “D_OPTIONS=TtrI”) in new stack – Executing [s@macro-dial-one:43] Dial(“SIP/110-000001d2”, “SIP/103,TtrI”) in new stack == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 – Called SIP/103 – Connected line update to SIP/110-000001d2 prevented. – SIP/103-000001d3 is ringing == Spawn extension (macro-dial-one, s, 43) exited non-zero on ‘SIP/110-000001d2’ in macro ‘dial-one’ == Spawn extension (macro-exten-vm, s, 16) exited non-zero on ‘SIP/110-000001d2’ in macro ‘exten-vm’ == Spawn extension (ext-local, 103, 2) exited non-zero on ‘SIP/110-000001d2’ – Executing [h@ext-local:1] Macro(“SIP/110-000001d2”, “hangupcall,”) in new stack – Executing [s@macro-hangupcall:1] GotoIf(“SIP/110-000001d2”, “1?theend”) in new stack – Goto (macro-hangupcall,s,3) – Executing [s@macro-hangupcall:3] ExecIf(“SIP/110-000001d2”, “0?Set(CDR(recordingfile)=)”) in new stack – Executing [s@macro-hangupcall:4] Hangup(“SIP/110-000001d2”, “”) in new stack == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/110-000001d2’ in macro ‘hangupcall’ == Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/110-000001d2’

THE PHONE THAT REFUSES TO RECEIVE CALLS:
== Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 – Executing [102@from-internal:1] GotoIf(“SIP/110-000001d4”, “1?ext-local,102,1”) in new stack – Goto (ext-local,102,1) – Executing [102@ext-local:1] Set(“SIP/110-000001d4”, “__RINGTIMER=15”) in new stack – Executing [102@ext-local:2] Macro(“SIP/110-000001d4”, “exten-vm,102,102,0,0,0”) in new stack – Executing [s@macro-exten-vm:1] Macro(“SIP/110-000001d4”, “user-callerid,”) in new stack – Executing [s@macro-user-callerid:1] Set(“SIP/110-000001d4”, “TOUCH_MONITOR=1400184806.468”) in new stack – Executing [s@macro-user-callerid:2] Set(“SIP/110-000001d4”, “AMPUSER=110”) in new stack – Executing [s@macro-user-callerid:3] GotoIf(“SIP/110-000001d4”, “0?report”) in new stack – Executing [s@macro-user-callerid:4] ExecIf(“SIP/110-000001d4”, “1?Set(REALCALLERIDNUM=110)”) in new stack – Executing [s@macro-user-callerid:5] Set(“SIP/110-000001d4”, “AMPUSER=110”) in new stack – Executing [s@macro-user-callerid:6] GotoIf(“SIP/110-000001d4”, “0?limit”) in new stack – Executing [s@macro-user-callerid:7] Set(“SIP/110-000001d4”, “AMPUSERCIDNAME=Conference Room”) in new stack – Executing [s@macro-user-callerid:8] GotoIf(“SIP/110-000001d4”, “0?report”) in new stack – Executing [s@macro-user-callerid:9] Set(“SIP/110-000001d4”, “AMPUSERCID=110”) in new stack – Executing [s@macro-user-callerid:10] Set(“SIP/110-000001d4”, “__DIAL_OPTIONS=Ttr”) in new stack – Executing [s@macro-user-callerid:11] Set(“SIP/110-000001d4”, “CALLERID(all)=“Conference Room” <110>”) in new stack – Executing [s@macro-user-callerid:12] GotoIf(“SIP/110-000001d4”, “0?limit”) in new stack – Executing [s@macro-user-callerid:13] ExecIf(“SIP/110-000001d4”, “0?Set(GROUP(concurrency_limit)=110)”) in new stack – Executing [s@macro-user-callerid:14] ExecIf(“SIP/110-000001d4”, “0?Set(CHANNEL(language)=)”) in new stack – Executing [s@macro-user-callerid:15] GosubIf(“SIP/110-000001d4”, “7?sub-ccss,s,1(macro-exten-vm,102)”) in new stack – Executing [s@sub-ccss:1] ExecIf(“SIP/110-000001d4”, “0?Return()”) in new stack – Executing [s@sub-ccss:2] Set(“SIP/110-000001d4”, “CCSS_SETUP=TRUE”) in new stack – Executing [s@sub-ccss:3] GosubIf(“SIP/110-000001d4”, “0?monitor_config,1(macro-exten-vm,102):monitor_default,1(macro-exten-vm,102)”) in new stack – Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/110-000001d4”, “1?is_exten”) in new stack – Goto (sub-ccss,monitor_default,4) – Executing [monitor_default@sub-ccss:4] Set(“SIP/110-000001d4”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack – Executing [monitor_default@sub-ccss:5] Set(“SIP/110-000001d4”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack – Executing [monitor_default@sub-ccss:6] Return(“SIP/110-000001d4”, “TRUE”) in new stack – Executing [s@sub-ccss:4] GosubIf(“SIP/110-000001d4”, “7?agent_config,1():agent_default,1()”) in new stack – Executing [agent_config@sub-ccss:1] Set(“SIP/110-000001d4”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack – Executing [agent_config@sub-ccss:2] Set(“SIP/110-000001d4”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack – Executing [agent_config@sub-ccss:3] Set(“SIP/110-000001d4”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack – Executing [agent_config@sub-ccss:4] Set(“SIP/110-000001d4”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack – Executing [agent_config@sub-ccss:5] Set(“SIP/110-000001d4”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack – Executing [agent_config@sub-ccss:6] ExecIf(“SIP/110-000001d4”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack – Executing [agent_config@sub-ccss:7] ExecIf(“SIP/110-000001d4”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack – Executing [agent_config@sub-ccss:8] ExecIf(“SIP/110-000001d4”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/110_102@from-ccss-)”) in new stack – Executing [agent_config@sub-ccss:9] Set(“SIP/110-000001d4”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack – Executing [agent_config@sub-ccss:10] Return(“SIP/110-000001d4”, “”) in new stack – Executing [s@sub-ccss:5] Set(“SIP/110-000001d4”, “DB(AMPUSER/110/ccss/last_number)=102”) in new stack – Executing [s@sub-ccss:6] Return(“SIP/110-000001d4”, “”) in new stack – Executing [s@macro-user-callerid:16] GotoIf(“SIP/110-000001d4”, “0?continue”) in new stack – Executing [s@macro-user-callerid:17] Set(“SIP/110-000001d4”, “__TTL=64”) in new stack – Executing [s@macro-user-callerid:18] GotoIf(“SIP/110-000001d4”, “1?continue”) in new stack – Goto (macro-user-callerid,s,29) – Executing [s@macro-user-callerid:29] Set(“SIP/110-000001d4”, “CALLERID(number)=110”) in new stack – Executing [s@macro-user-callerid:30] Set(“SIP/110-000001d4”, “CALLERID(name)=Conference Room”) in new stack – Executing [s@macro-user-callerid:31] Set(“SIP/110-000001d4”, “CDR(cnum)=110”) in new stack – Executing [s@macro-user-callerid:32] Set(“SIP/110-000001d4”, “CDR(cnam)=Conference Room”) in new stack – Executing [s@macro-user-callerid:33] Set(“SIP/110-000001d4”, “CHANNEL(language)=en”) in new stack – Executing [s@macro-exten-vm:2] Set(“SIP/110-000001d4”, “RingGroupMethod=none”) in new stack – Executing [s@macro-exten-vm:3] Set(“SIP/110-000001d4”, “__EXTTOCALL=102”) in new stack – Executing [s@macro-exten-vm:4] Set(“SIP/110-000001d4”, “__PICKUPMARK=102”) in new stack – Executing [s@macro-exten-vm:5] Set(“SIP/110-000001d4”, “RT=15”) in new stack – Executing [s@macro-exten-vm:6] ExecIf(“SIP/110-000001d4”, “0?Macro(vm,102,DIRECTDIAL,)”) in new stack – Executing [s@macro-exten-vm:7] ExecIf(“SIP/110-000001d4”, “0?MacroExit()”) in new stack – Executing [s@macro-exten-vm:8] Gosub(“SIP/110-000001d4”, “sub-record-check,s,1(exten,102,)”) in new stack – Executing [s@sub-record-check:1] Set(“SIP/110-000001d4”, “REC_POLICY_MODE_SAVE=”) in new stack – Executing [s@sub-record-check:2] GotoIf(“SIP/110-000001d4”, “1?check”) in new stack – Goto (sub-record-check,s,7) – Executing [s@sub-record-check:7] Set(“SIP/110-000001d4”, “__MON_FMT=wav”) in new stack – Executing [s@sub-record-check:8] GotoIf(“SIP/110-000001d4”, “1?next”) in new stack – Goto (sub-record-check,s,11) – Executing [s@sub-record-check:11] ExecIf(“SIP/110-000001d4”, “0?Return()”) in new stack – Executing [s@sub-record-check:12] ExecIf(“SIP/110-000001d4”, “0?Set(__REC_POLICY_MODE=)”) in new stack – Executing [s@sub-record-check:13] GotoIf(“SIP/110-000001d4”, “0?exten,1”) in new stack – Executing [s@sub-record-check:14] Set(“SIP/110-000001d4”, “__REC_STATUS=INITIALIZED”) in new stack – Executing [s@sub-record-check:15] Set(“SIP/110-000001d4”, “NOW=1400184806”) in new stack – Executing [s@sub-record-check:16] Set(“SIP/110-000001d4”, “__DAY=15”) in new stack – Executing [s@sub-record-check:17] Set(“SIP/110-000001d4”, “__MONTH=05”) in new stack – Executing [s@sub-record-check:18] Set(“SIP/110-000001d4”, “__YEAR=2014”) in new stack – Executing [s@sub-record-check:19] Set(“SIP/110-000001d4”, “__TIMESTR=20140515-161326”) in new stack – Executing [s@sub-record-check:20] Set(“SIP/110-000001d4”, “__FROMEXTEN=110”) in new stack – Executing [s@sub-record-check:21] Set(“SIP/110-000001d4”, “__CALLFILENAME=exten-102-110-20140515-161326-1400184806.468”) in new stack – Executing [s@sub-record-check:22] Goto(“SIP/110-000001d4”, “exten,1”) in new stack – Goto (sub-record-check,exten,1) – Executing [exten@sub-record-check:1] GotoIf(“SIP/110-000001d4”, “0?callee”) in new stack – Executing [exten@sub-record-check:2] Set(“SIP/110-000001d4”, “__REC_POLICY_MODE=dontcare”) in new stack – Executing [exten@sub-record-check:3] GotoIf(“SIP/110-000001d4”, “1?caller”) in new stack – Goto (sub-record-check,exten,10) – Executing [exten@sub-record-check:10] Set(“SIP/110-000001d4”, “__REC_POLICY_MODE=dontcare”) in new stack – Executing [exten@sub-record-check:11] GosubIf(“SIP/110-000001d4”, “0?record,1(exten,102,110)”) in new stack – Executing [exten@sub-record-check:12] Return(“SIP/110-000001d4”, “”) in new stack – Executing [s@macro-exten-vm:9] GotoIf(“SIP/110-000001d4”, “1?macrodial”) in new stack – Goto (macro-exten-vm,s,15) – Executing [s@macro-exten-vm:15] GosubIf(“SIP/110-000001d4”, “0?clrheader,1()”) in new stack – Executing [s@macro-exten-vm:16] Macro(“SIP/110-000001d4”, “dial-one,15,Ttr,102”) in new stack – Executing [s@macro-dial-one:1] Set(“SIP/110-000001d4”, “DEXTEN=102”) in new stack – Executing [s@macro-dial-one:2] Set(“SIP/110-000001d4”, “DIALSTATUS_CW=”) in new stack – Executing [s@macro-dial-one:3] GosubIf(“SIP/110-000001d4”, “0?screen,1()”) in new stack – Executing [s@macro-dial-one:4] GosubIf(“SIP/110-000001d4”, “0?cf,1()”) in new stack – Executing [s@macro-dial-one:5] GotoIf(“SIP/110-000001d4”, “1?skip1”) in new stack – Goto (macro-dial-one,s,8) – Executing [s@macro-dial-one:8] GotoIf(“SIP/110-000001d4”, “0?nodial”) in new stack – Executing [s@macro-dial-one:9] GotoIf(“SIP/110-000001d4”, “0?continue”) in new stack – Executing [s@macro-dial-one:10] Set(“SIP/110-000001d4”, “EXTHASCW=ENABLED”) in new stack – Executing [s@macro-dial-one:11] GotoIf(“SIP/110-000001d4”, “0?next1:cwinusebusy”) in new stack – Goto (macro-dial-one,s,23) – Executing [s@macro-dial-one:23] GotoIf(“SIP/110-000001d4”, “1?next3:continue”) in new stack – Goto (macro-dial-one,s,24) – Executing [s@macro-dial-one:24] ExecIf(“SIP/110-000001d4”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack – Executing [s@macro-dial-one:25] GotoIf(“SIP/110-000001d4”, “0?nodial”) in new stack – Executing [s@macro-dial-one:26] GosubIf(“SIP/110-000001d4”, “1?dstring,1():dlocal,1()”) in new stack – Executing [dstring@macro-dial-one:1] Set(“SIP/110-000001d4”, “DSTRING=”) in new stack – Executing [dstring@macro-dial-one:2] Set(“SIP/110-000001d4”, “DEVICES=102”) in new stack – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/110-000001d4”, “0?Return()”) in new stack – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/110-000001d4”, “0?Set(DEVICES=02)”) in new stack – Executing [dstring@macro-dial-one:5] Set(“SIP/110-000001d4”, “LOOPCNT=1”) in new stack – Executing [dstring@macro-dial-one:6] Set(“SIP/110-000001d4”, “ITER=1”) in new stack – Executing [dstring@macro-dial-one:7] Set(“SIP/110-000001d4”, “THISDIAL=SIP/102”) in new stack – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/110-000001d4”, “1?zap2dahdi,1()”) in new stack – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/110-000001d4”, “0?Return()”) in new stack – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/110-000001d4”, “NEWDIAL=”) in new stack – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/110-000001d4”, “LOOPCNT2=1”) in new stack – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/110-000001d4”, “ITER2=1”) in new stack – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/110-000001d4”, “THISPART2=SIP/102”) in new stack – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/110-000001d4”, “0?Set(THISPART2=DAHDI/102)”) in new stack – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/110-000001d4”, “NEWDIAL=SIP/102&”) in new stack – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/110-000001d4”, “ITER2=2”) in new stack – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/110-000001d4”, “0?begin2”) in new stack – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/110-000001d4”, “THISDIAL=SIP/102”) in new stack – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/110-000001d4”, “”) in new stack – Executing [dstring@macro-dial-one:9] Set(“SIP/110-000001d4”, “DSTRING=SIP/102&”) in new stack – Executing [dstring@macro-dial-one:10] Set(“SIP/110-000001d4”, “ITER=2”) in new stack – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/110-000001d4”, “0?begin”) in new stack – Executing [dstring@macro-dial-one:12] Set(“SIP/110-000001d4”, “DSTRING=SIP/102”) in new stack – Executing [dstring@macro-dial-one:13] Return(“SIP/110-000001d4”, “”) in new stack – Executing [s@macro-dial-one:27] GotoIf(“SIP/110-000001d4”, “0?nodial”) in new stack – Executing [s@macro-dial-one:28] GotoIf(“SIP/110-000001d4”, “0?skiptrace”) in new stack – Executing [s@macro-dial-one:29] GosubIf(“SIP/110-000001d4”, “1?ctset,1():ctclear,1()”) in new stack – Executing [ctset@macro-dial-one:1] Set(“SIP/110-000001d4”, “DB(CALLTRACE/102)=110”) in new stack – Executing [ctset@macro-dial-one:2] Return(“SIP/110-000001d4”, “”) in new stack – Executing [s@macro-dial-one:30] Set(“SIP/110-000001d4”, “D_OPTIONS=Ttr”) in new stack – Executing [s@macro-dial-one:31] ExecIf(“SIP/110-000001d4”, “0?SIPAddHeader(Alert-Info: )”) in new stack – Executing [s@macro-dial-one:32] ExecIf(“SIP/110-000001d4”, “0?SIPAddHeader()”) in new stack – Executing [s@macro-dial-one:33] ExecIf(“SIP/110-000001d4”, “0?Set(CHANNEL(musicclass)=)”) in new stack – Executing [s@macro-dial-one:34] GosubIf(“SIP/110-000001d4”, “0?qwait,1()”) in new stack – Executing [s@macro-dial-one:35] Set(“SIP/110-000001d4”, “__CWIGNORE=”) in new stack – Executing [s@macro-dial-one:36] Set(“SIP/110-000001d4”, “__KEEPCID=TRUE”) in new stack – Executing [s@macro-dial-one:37] GotoIf(“SIP/110-000001d4”, “0?usegoto,1”) in new stack – Executing [s@macro-dial-one:38] GotoIf(“SIP/110-000001d4”, “0?godial”) in new stack – Executing [s@macro-dial-one:39] Gosub(“SIP/110-000001d4”, “sub-presencestate-display,s,1(102)”) in new stack – Executing [s@sub-presencestate-display:1] Goto(“SIP/110-000001d4”, “state-,1”) in new stack – Goto (sub-presencestate-display,state-,1) – Executing [state-@sub-presencestate-display:1] Set(“SIP/110-000001d4”, “PRESENCESTATE_DISPLAY=”) in new stack – Executing [state-@sub-presencestate-display:2] Return(“SIP/110-000001d4”, “”) in new stack – Executing [s@macro-dial-one:40] Set(“SIP/110-000001d4”, “CONNECTEDLINE(name,i)=Iris Sribman”) in new stack – Executing [s@macro-dial-one:41] Set(“SIP/110-000001d4”, “CONNECTEDLINE(num)=102”) in new stack – Executing [s@macro-dial-one:42] Set(“SIP/110-000001d4”, “D_OPTIONS=TtrI”) in new stack – Executing [s@macro-dial-one:43] Dial(“SIP/110-000001d4”, “SIP/102,15,TtrI”) in new stack == Everyone is busy/congested at this time (1:0/0/1) – Executing [s@macro-dial-one:44] ExecIf(“SIP/110-000001d4”, “0?MacroExit()”) in new stack – Executing [s@macro-dial-one:45] ExecIf(“SIP/110-000001d4”, “0?Set(DIALSTATUS=)”) in new stack – Executing [s@macro-dial-one:46] GosubIf(“SIP/110-000001d4”, “0?s-CHANUNAVAIL,1()”) in new stack – Executing [s@macro-dial-one:47] MacroExit(“SIP/110-000001d4”, “”) in new stack – Executing [s@macro-exten-vm:17] Set(“SIP/110-000001d4”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack – Executing [s@macro-exten-vm:18] GosubIf(“SIP/110-000001d4”, “0?docfu,1()”) in new stack – Executing [s@macro-exten-vm:19] GosubIf(“SIP/110-000001d4”, “0?docfb,1()”) in new stack – Executing [s@macro-exten-vm:20] Set(“SIP/110-000001d4”, “DIALSTATUS=CHANUNAVAIL”) in new stack – Executing [s@macro-exten-vm:21] ExecIf(“SIP/110-000001d4”, “0?MacroExit()”) in new stack – Executing [s@macro-exten-vm:22] GotoIf(“SIP/110-000001d4”, “0?s-CHANUNAVAIL,1”) in new stack – Executing [s@macro-exten-vm:23] Macro(“SIP/110-000001d4”, “vm,102,CHANUNAVAIL,”) in new stack – Executing [s@macro-vm:1] Macro(“SIP/110-000001d4”, “user-callerid,SKIPTTL”) in new stack – Executing [s@macro-user-callerid:1] Set(“SIP/110-000001d4”, “TOUCH_MONITOR=1400184806.468”) in new stack – Executing [s@macro-user-callerid:2] Set(“SIP/110-000001d4”, “AMPUSER=110”) in new stack – Executing [s@macro-user-callerid:3] GotoIf(“SIP/110-000001d4”, “15?report”) in new stack – Goto (macro-user-callerid,s,16) – Executing [s@macro-user-callerid:16] GotoIf(“SIP/110-000001d4”, “1?continue”) in new stack – Goto (macro-user-callerid,s,29) – Executing [s@macro-user-callerid:29] Set(“SIP/110-000001d4”, “CALLERID(number)=110”) in new stack – Executing [s@macro-user-callerid:30] Set(“SIP/110-000001d4”, “CALLERID(name)=Conference Room”) in new stack – Executing [s@macro-user-callerid:31] Set(“SIP/110-000001d4”, “CDR(cnum)=110”) in new stack – Executing [s@macro-user-callerid:32] Set(“SIP/110-000001d4”, “CDR(cnam)=Conference Room”) in new stack – Executing [s@macro-user-callerid:33] Set(“SIP/110-000001d4”, “CHANNEL(language)=en”) in new stack – Executing [s@macro-vm:2] Set(“SIP/110-000001d4”, “VMGAIN=”) in new stack – Executing [s@macro-vm:3] Macro(“SIP/110-000001d4”, “blkvm-check,”) in new stack – Executing [s@macro-blkvm-check:1] Set(“SIP/110-000001d4”, “GOSUB_RETVAL=”) in new stack – Executing [s@macro-blkvm-check:2] ExecIf(“SIP/110-000001d4”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack – Executing [s@macro-blkvm-check:3] MacroExit(“SIP/110-000001d4”, “”) in new stack – Executing [s@macro-vm:4] GotoIf(“SIP/110-000001d4”, “1?vmx,1”) in new stack – Goto (macro-vm,vmx,1) – Executing [vmx@macro-vm:1] Set(“SIP/110-000001d4”, “MEXTEN=102”) in new stack – Executing [vmx@macro-vm:2] Set(“SIP/110-000001d4”, “MMODE=CHANUNAVAIL”) in new stack – Executing [vmx@macro-vm:3] Set(“SIP/110-000001d4”, “RETVM=”) in new stack – Executing [vmx@macro-vm:4] Set(“SIP/110-000001d4”, “MODE=unavail”) in new stack – Executing [vmx@macro-vm:5] GotoIf(“SIP/110-000001d4”, “1?chknomsg”) in new stack – Goto (macro-vm,vmx,7) – Executing [vmx@macro-vm:7] GotoIf(“SIP/110-000001d4”, “0?s-CHANUNAVAIL,1”) in new stack – Executing [vmx@macro-vm:8] GotoIf(“SIP/110-000001d4”, “1?notdirect”) in new stack – Goto (macro-vm,vmx,10) – Executing [vmx@macro-vm:10] NoOp(“SIP/110-000001d4”, "Checking if ext 102 is enabled: ") in new stack – Executing [vmx@macro-vm:11] GotoIf(“SIP/110-000001d4”, “1?s-CHANUNAVAIL,1”) in new stack – Goto (macro-vm,s-CHANUNAVAIL,1) – Executing [s-CHANUNAVAIL@macro-vm:1] Macro(“SIP/110-000001d4”, “get-vmcontext,102”) in new stack – Executing [s@macro-get-vmcontext:1] Set(“SIP/110-000001d4”, “VMCONTEXT=default”) in new stack – Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/110-000001d4”, “0?200:300”) in new stack – Goto (macro-get-vmcontext,s,300) – Executing [s@macro-get-vmcontext:300] NoOp(“SIP/110-000001d4”, “”) in new stack – Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail(“SIP/110-000001d4”, “102@default,u”) in new stack – <SIP/110-000001d4> Playing ‘vm-theperson.ulaw’ (language ‘en’) > 0x7fa70000ff80 – Probation passed - setting RTP source address to 73.179.79.63:4008 – <SIP/110-000001d4> Playing ‘digits/1.ulaw’ (language ‘en’) – <SIP/110-000001d4> Playing ‘digits/0.ulaw’ (language ‘en’) – <SIP/110-000001d4> Playing ‘digits/2.ulaw’ (language ‘en’) – <SIP/110-000001d4> Playing ‘vm-isunavail.ulaw’ (language ‘en’) – <SIP/110-000001d4> Playing ‘vm-intro.ulaw’ (language ‘en’) == Spawn extension (macro-vm, s-CHANUNAVAIL, 2) exited non-zero on ‘SIP/110-000001d4’ in macro ‘vm’ == Spawn extension (macro-exten-vm, s, 23) exited non-zero on ‘SIP/110-000001d4’ in macro ‘exten-vm’ == Spawn extension (ext-local, 102, 2) exited non-zero on ‘SIP/110-000001d4’ – Executing [h@ext-local:1] Macro(“SIP/110-000001d4”, “hangupcall,”) in new stack – Executing [s@macro-hangupcall:1] GotoIf(“SIP/110-000001d4”, “1?theend”) in new stack – Goto (macro-hangupcall,s,3) – Executing [s@macro-hangupcall:3] ExecIf(“SIP/110-000001d4”, “0?Set(CDR(recordingfile)=)”) in new stack – Executing [s@macro-hangupcall:4] Hangup(“SIP/110-000001d4”, “”) in new stack == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/110-000001d4’ in macro ‘hangupcall’ == Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/110-000001d4’

Any idea what the problem might be? I can attach screenshot of the Cisco phone’s configuration page if needed.

dicko · May 15, 2014, 8:43pm

-- Executing [s@macroEmail links icon-dial-one:43] Dial("SIP/110-000001d4", "SIP/102,15,TtrI") in new stack

== Everyone is busy/congested at this time (1:0/0/1)

Check sip show peers and if it there then that DND is not set on the phone.

tamiral · May 15, 2014, 10:35pm

Thanks for the response, dicko! My “sip show peers” output does show these two extensions in UNREACHABLE mode, but how can I find out why, and how can I resolve this? I’ve made sure that both phones are NOT in DND mode.


Name/username             Host                                    Dyn Forcerport Comedia    ACL Port     Status      Description                      
101/101                   73.179.79.63                             D  Yes        Yes         A  5060     UNREACHABLE                                  
102/102                   73.179.79.63                             D  Yes        Yes         A  5060     UNREACHABLE                                  
103/103                   107.220.246.59                           D  Yes        Yes         A  5060     OK (39 ms)                                   
104                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
105                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
106                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
110/110                   (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
150                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
199                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

dicko · May 15, 2014, 10:40pm

You probably shouldn’t have two devices at the same ip trying and use the same port to do their sip registrations.

dicko · May 15, 2014, 10:48pm

(It’s probably Comcast messing with you Never leek sensitive info when posting !!)

tamiral · May 15, 2014, 11:27pm

OK, what is the common practice then, to handle multiple phones from the same IP? Should I turn off the NAT feature on the phones? How do most organizations using a hosted PBX handling this? I’m sure I don’t want to open any additional ports other than port 5060, no?

dicko · May 15, 2014, 11:35pm

To get past badly configured PNAT routering you can specify a particular port for each co-located phone to use SIP for registration, do the same on the phone. (Personally I never use 5060 by default anywhere both for security reasons and to avoid well meaning but bad “SIP helper” functions in many routers)

tamiral · May 15, 2014, 11:53pm

Two quick questions for you:

What is PNAT? I’m familiar with DNAT (destination) and SNAT (source).
In order to use multiple ports on my PBX, where do I enable this exactly? Should I run multiple instances or is there a way in FreePBX to specify multiple listening ports?

tamiral · May 16, 2014, 12:02am

I have changed the SIP registration on each phone so they don’t match but am still having the same issue. Any idea why? Here is my updated outcome of “sip show peers”. Note the changed ports, same IPs. Problems persists. Any ideas?


101/101                   xx.xx.xx.63                             D  Yes        Yes         A  5001     UNREACHABLE                                  
102/102                   xx.xx.xx.63                             D  Yes        Yes         A  5002     UNREACHABLE                                  
103/103                   xx.xx.xx.59                           D  Yes        Yes         A  5060     OK (39 ms)                                   
104                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
105                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
106                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
110/110                   (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
150                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN                                      
199                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

dicko · May 16, 2014, 12:11am

The P is for port some call it NPAT and you really don’t want anything translating the rtp port. In your sip.conf [general] hierarchy add

bindport=34567 ;for example, FreePBX will let you do that in the GUI

(No more “drive-passed’s”)

you can individually, per endpoint, change it as necessary, many VSP can’t or wont change it from 5060 so explicitly state the port used in your trunks for your trunks (which makes no sense at all)

tamiral · May 16, 2014, 12:04pm

OK, I’m doing my best to understand this, but nothing… Based on your recommendation, I’ve changed the PBX’s bind port, as well as each extension’s specific port. If you’ll look at the ‘sip show peers’ output above, you’ll see that extension 101 uses port 5001 and ext. 102 uses port 5002. Why, in this case, are they still unreachable? What am I doing wrong exactly…???

dicko · May 16, 2014, 12:23pm

sip set debug ip xx.xx.xx.63

tamiral · May 16, 2014, 1:22pm

here is my “sip set debug ip” output for this specific IP which has multiple IP phones connecting from it. I thought setting different ports was enough (I also changed the bind port), but they’re still unreachable. Here are the log lines:


Retransmitting #3 (NAT) to 73.179.79.63:5002:
OPTIONS sip:[email protected]:5002 SIP/2.0
Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK63560c66;rport
Max-Forwards: 70
From: "Unknown" ;tag=as2ae95871
To: 
Contact: 
Call-ID: [email protected]:5080
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.8.1)
Date: Fri, 16 May 2014 13:19:42 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK63560c66;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as2ae95871

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:42 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

host-67-23-69-36*CLI> sip show peers

Name/username             Host                                    Dyn Forcerport Comedia    ACL Port     Status      Description

101/101                   73.179.79.63                             D  Yes        Yes         A  5001     OK (22 ms)

102/102                   73.179.79.63                             D  Yes        Yes         A  5002     UNREACHABLE

103/103                   (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

104                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

105                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

106                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

110                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

150                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

199                       (Unspecified)                            D  Yes        Yes         A  0        UNKNOWN

voxbeam_inbound/admin     95.211.119.240                              No         No             5060     Unmonitored

voxbeam_outbound/admin    108.59.2.133                                No         No             5060     Unmonitored

11 sip peers [Monitored: 1 online, 8 offline Unmonitored: 2 online, 0 offline]

Reliably Transmitting (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK67333558;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as059b1dd9

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:50 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK67333558;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as059b1dd9

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:50 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #2 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK67333558;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as059b1dd9

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:50 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #3 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK67333558;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as059b1dd9

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:50 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK67333558;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as059b1dd9

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:50 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

Reliably Transmitting (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK0f097284;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as777a1d39

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:56 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK0f097284;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as777a1d39

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:56 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #2 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK0f097284;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as777a1d39

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:56 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #3 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK0f097284;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as777a1d39

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:56 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK0f097284;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as777a1d39

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:19:56 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

Reliably Transmitting (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK26b8a753;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as5f2f46fe

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:04 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK26b8a753;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as5f2f46fe

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:04 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #2 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK26b8a753;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as5f2f46fe

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:04 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #3 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK26b8a753;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as5f2f46fe

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:04 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK26b8a753;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as5f2f46fe

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:04 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

Reliably Transmitting (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5897a9bd;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as3c025590

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:10 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5897a9bd;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as3c025590

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:10 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #2 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5897a9bd;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as3c025590

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:10 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #3 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5897a9bd;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as3c025590

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:10 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5897a9bd;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as3c025590

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:10 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

Reliably Transmitting (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK7d6e3ad8;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as34be330b

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:18 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK7d6e3ad8;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as34be330b

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:18 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #2 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK7d6e3ad8;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as34be330b

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:18 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #3 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK7d6e3ad8;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as34be330b

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:18 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK7d6e3ad8;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as34be330b

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:18 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

Reliably Transmitting (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK18b80bb6;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as28c828e6

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:24 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK18b80bb6;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as28c828e6

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:24 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #2 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK18b80bb6;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as28c828e6

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:24 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #3 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK18b80bb6;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as28c828e6

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:24 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK18b80bb6;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as28c828e6

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:24 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

Reliably Transmitting (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5dfae662;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as59e7debb

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:32 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5dfae662;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as59e7debb

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:32 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #2 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5dfae662;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as59e7debb

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:32 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #3 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5dfae662;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as59e7debb

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:32 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #4 (NAT) to 73.179.79.63:5001:

OPTIONS sip:[email protected]:5001 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK5dfae662;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as59e7debb

To: sip:[email protected]:5001

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:32 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Really destroying SIP dialog ‘[email protected]:5080’ Method: OPTIONS

Reliably Transmitting (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK054e42e9;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as6f60939a

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:38 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

Retransmitting #1 (NAT) to 73.179.79.63:5002:

OPTIONS sip:[email protected]:5002 SIP/2.0

Via: SIP/2.0/UDP 67.23.69.36:5080;branch=z9hG4bK054e42e9;rport

Max-Forwards: 70

From: “Unknown” sip:[email protected]:5080;tag=as6f60939a

To: sip:[email protected]:5002

Contact: sip:[email protected]:5080

Call-ID: [email protected]:5080

CSeq: 102 OPTIONS

User-Agent: FPBX-2.11.0(11.8.1)

Date: Fri, 16 May 2014 13:20:38 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

Content-Length: 0

dicko · May 16, 2014, 1:27pm

You have a natting problem through the comcast box, try using RFC3581 natting on those extensions.

tamiral · May 16, 2014, 3:57pm

OK, problem seems to have been solved. I changed the NAT settings on FreePBX to ROUTE. Thanks dicko! Any implications there or is that the right move?

dicko · May 16, 2014, 4:59pm

Not if it works , SIP and the ensuing SDP exchanges can be tricky to get through many router/firewalls especially if you don’t have direct access to them, sip debug and tcpdump will usually show what is going wrong. Glad you got it fixed though.