Remote PJSIP Endpoints Straight to VM

hardocp · December 7, 2018, 6:58pm

I am running Asterisk 16.0.0 and Freepbx 14.0.5.2

My current setup is as follows – Asterisk is hosted on a cloud VM with a public IP

Asterisk is running fine as are the endpoints

All endpoints connect to Asterisk via PJSIP and i am able to make outbound calls and complete an echo test from each endpoint

Here is an example of what my endpoints look like in the CLI

Endpoint: 121/121 Not in use 0 of inf
InAuth: 121-auth/121
Aor: 121 1
Contact: 121/sip:[email protected]:47329;transport=TL c0eea25396 Avail 17.906
Transport: 0.0.0.0-tls tls 3 96 0.0.0.0:5061

Fine

My issue is that when i try to call from extension to extension or if i make a call to my DID and then try to connect to an extension the result is straight to VM

Here is the call log:

== Using SIP RTP Audio TOS bits 184
== Using SIP RTP Audio CoS mark 5
– Executing [125@from-internal:1] GotoIf(“PJSIP/121-0000000d”, “1?ext-local,125,1:followme-check,125,1”) in new stack
– Goto (ext-local,125,1)
– Executing [125@ext-local:1] Set(“PJSIP/121-0000000d”, “__RINGTIMER=15”) in new stack
– Executing [125@ext-local:2] Macro(“PJSIP/121-0000000d”, “exten-vm,125,125,0,0,0”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“PJSIP/121-0000000d”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/121-0000000d”, “TOUCH_MONITOR=1544208882.13”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/121-0000000d”, “AMPUSER=121”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/121-0000000d”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/121-0000000d”, “1?Set(REALCALLERIDNUM=121)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“PJSIP/121-0000000d”, “AMPUSER=121”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/121-0000000d”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“PJSIP/121-0000000d”, “AMPUSERCIDNAME=xxx”) in new stack
– Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/121-0000000d”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
– Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/121-0000000d”, “0?report”) in new stack
– Executing [s@macro-user-callerid:10] Set(“PJSIP/121-0000000d”, “AMPUSERCID=121”) in new stack
– Executing [s@macro-user-callerid:11] Set(“PJSIP/121-0000000d”, “__DIAL_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-user-callerid:12] Set(“PJSIP/121-0000000d”, “CALLERID(all)=“Asher Toporovsky” <121>”) in new stack
– Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/121-0000000d”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/121-0000000d”, “0?Set(GROUP(concurrency_limit)=121)”) in new stack
– Executing [s@macro-user-callerid:15] NoOp(“PJSIP/121-0000000d”, “Macro Depth is 2”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/121-0000000d”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,17)
– Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/121-0000000d”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:18] Set(“PJSIP/121-0000000d”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/121-0000000d”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,35)
– Executing [s@macro-user-callerid:35] Set(“PJSIP/121-0000000d”, “CALLERID(number)=121”) in new stack
– Executing [s@macro-user-callerid:36] Set(“PJSIP/121-0000000d”, “CALLERID(name)=Asher Toporovsky”) in new stack
– Executing [s@macro-user-callerid:37] GotoIf(“PJSIP/121-0000000d”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:38] Set(“PJSIP/121-0000000d”, “CDR(cnam)=Asher Toporovsky”) in new stack
– Executing [s@macro-user-callerid:39] Set(“PJSIP/121-0000000d”, “CDR(cnum)=121”) in new stack
– Executing [s@macro-user-callerid:40] Set(“PJSIP/121-0000000d”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-exten-vm:2] Set(“PJSIP/121-0000000d”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“PJSIP/121-0000000d”, “__EXTTOCALL=125”) in new stack
– Executing [s@macro-exten-vm:4] Set(“PJSIP/121-0000000d”, “__PICKUPMARK=125”) in new stack
– Executing [s@macro-exten-vm:5] Set(“PJSIP/121-0000000d”, “RT=15”) in new stack
– Executing [s@macro-exten-vm:6] Gosub(“PJSIP/121-0000000d”, “sub-record-check,s,1(exten,125,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“PJSIP/121-0000000d”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“PJSIP/121-0000000d”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“PJSIP/121-0000000d”, “NOW=1544208882”) in new stack
– Executing [s@sub-record-check:4] Set(“PJSIP/121-0000000d”, “__DAY=07”) in new stack
– Executing [s@sub-record-check:5] Set(“PJSIP/121-0000000d”, “__MONTH=12”) in new stack
– Executing [s@sub-record-check:6] Set(“PJSIP/121-0000000d”, “__YEAR=2018”) in new stack
– Executing [s@sub-record-check:7] Set(“PJSIP/121-0000000d”, “__TIMESTR=20181207-135442”) in new stack
– Executing [s@sub-record-check:8] Set(“PJSIP/121-0000000d”, “__FROMEXTEN=121”) in new stack
– Executing [s@sub-record-check:9] Set(“PJSIP/121-0000000d”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“PJSIP/121-0000000d”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“PJSIP/121-0000000d”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“PJSIP/121-0000000d”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“PJSIP/121-0000000d”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“PJSIP/121-0000000d”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“PJSIP/121-0000000d”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] NoOp(“PJSIP/121-0000000d”, “Exten Recording Check between 121 and 125”) in new stack
– Executing [exten@sub-record-check:2] Set(“PJSIP/121-0000000d”, “CALLTYPE=internal”) in new stack
– Executing [exten@sub-record-check:3] ExecIf(“PJSIP/121-0000000d”, “0?Set(CALLTYPE=)”) in new stack
– Executing [exten@sub-record-check:4] Set(“PJSIP/121-0000000d”, “CALLEE=dontcare”) in new stack
– Executing [exten@sub-record-check:5] ExecIf(“PJSIP/121-0000000d”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [exten@sub-record-check:6] GotoIf(“PJSIP/121-0000000d”, “0?callee”) in new stack
– Executing [exten@sub-record-check:7] GotoIf(“PJSIP/121-0000000d”, “1?caller”) in new stack
– Goto (sub-record-check,exten,13)
– Executing [exten@sub-record-check:13] Set(“PJSIP/121-0000000d”, “RECMODE=dontcare”) in new stack
– Executing [exten@sub-record-check:14] ExecIf(“PJSIP/121-0000000d”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:15] ExecIf(“PJSIP/121-0000000d”, “1?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:16] Gosub(“PJSIP/121-0000000d”, “recordcheck,1(dontcare,internal,125)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/121-0000000d”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/121-0000000d”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“PJSIP/121-0000000d”, “”) in new stack
– Executing [exten@sub-record-check:17] Return(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-exten-vm:7] GotoIf(“PJSIP/121-0000000d”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,13)
– Executing [s@macro-exten-vm:13] GosubIf(“PJSIP/121-0000000d”, “0?clrheader,1()”) in new stack
– Executing [s@macro-exten-vm:14] Macro(“PJSIP/121-0000000d”, “dial-one,15,HhTtr,125”) in new stack
– Executing [s@macro-dial-one:1] Set(“PJSIP/121-0000000d”, “DEXTEN=125”) in new stack
– Executing [s@macro-dial-one:2] ExecIf(“PJSIP/121-0000000d”, “0?Set(__EXTTOCALL=125)”) in new stack
– Executing [s@macro-dial-one:3] Set(“PJSIP/121-0000000d”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“PJSIP/121-0000000d”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:5] GosubIf(“PJSIP/121-0000000d”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:6] GotoIf(“PJSIP/121-0000000d”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,9)
– Executing [s@macro-dial-one:9] GotoIf(“PJSIP/121-0000000d”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:10] GotoIf(“PJSIP/121-0000000d”, “0?continue”) in new stack
– Executing [s@macro-dial-one:11] Set(“PJSIP/121-0000000d”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:12] GotoIf(“PJSIP/121-0000000d”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [s@macro-dial-one:24] GotoIf(“PJSIP/121-0000000d”, “0?next3:continue”) in new stack
– Goto (macro-dial-one,s,26)
– Executing [s@macro-dial-one:26] GotoIf(“PJSIP/121-0000000d”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:27] GosubIf(“PJSIP/121-0000000d”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“PJSIP/121-0000000d”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“PJSIP/121-0000000d”, “DEVICES=125”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/121-0000000d”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/121-0000000d”, “0?Set(DEVICES=25)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“PJSIP/121-0000000d”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“PJSIP/121-0000000d”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“PJSIP/121-0000000d”, “THISDIAL=PJSIP/125”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“PJSIP/121-0000000d”, “1?zap2dahdi,1()”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“PJSIP/121-0000000d”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“PJSIP/121-0000000d”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“PJSIP/121-0000000d”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“PJSIP/121-0000000d”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“PJSIP/121-0000000d”, “THISPART2=PJSIP/125”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“PJSIP/121-0000000d”, “0?Set(THISPART2=DAHDIIP/125)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“PJSIP/121-0000000d”, “NEWDIAL=PJSIP/125&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“PJSIP/121-0000000d”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“PJSIP/121-0000000d”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“PJSIP/121-0000000d”, “THISDIAL=PJSIP/125”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“PJSIP/121-0000000d”, “”) in new stack
– Executing [dstring@macro-dial-one:9] GotoIf(“PJSIP/121-0000000d”, “0?docheck”) in new stack
– Executing [dstring@macro-dial-one:10] NoOp(“PJSIP/121-0000000d”, “Debug: Found PJSIP Destination PJSIP/125”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“PJSIP/121-0000000d”, “0?doset”) in new stack
– Executing [dstring@macro-dial-one:12] NoOp(“PJSIP/121-0000000d”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
– Executing [dstring@macro-dial-one:13] Set(“PJSIP/121-0000000d”, “THISDIAL=PJSIP/125/sip:[email protected]:62349;transport=TLS”) in new stack
– Executing [dstring@macro-dial-one:14] ExecIf(“PJSIP/121-0000000d”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
– Executing [dstring@macro-dial-one:15] GotoIf(“PJSIP/121-0000000d”, “0?skipset”) in new stack
– Executing [dstring@macro-dial-one:16] Set(“PJSIP/121-0000000d”, “DSTRING=PJSIP/125/sip:[email protected]:62349;transport=TLS&”) in new stack
– Executing [dstring@macro-dial-one:17] Set(“PJSIP/121-0000000d”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:18] GotoIf(“PJSIP/121-0000000d”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:19] ExecIf(“PJSIP/121-0000000d”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:20] Set(“PJSIP/121-0000000d”, “DSTRING=PJSIP/125/sip:[email protected]:62349;transport=TLS”) in new stack
– Executing [dstring@macro-dial-one:21] Return(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“PJSIP/121-0000000d”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:29] GotoIf(“PJSIP/121-0000000d”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:30] GosubIf(“PJSIP/121-0000000d”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“PJSIP/121-0000000d”, “DB(CALLTRACE/125)=121”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-dial-one:31] Set(“PJSIP/121-0000000d”, “D_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-dial-one:32] GosubIf(“PJSIP/121-0000000d”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [s@macro-dial-one:33] NoOp(“PJSIP/121-0000000d”, "Blind Transfer: , Attended Transfer: , User: 121, Alert Info: ") in new stack
– Executing [s@macro-dial-one:34] ExecIf(“PJSIP/121-0000000d”, “1?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:35] ExecIf(“PJSIP/121-0000000d”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:36] ExecIf(“PJSIP/121-0000000d”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:37] ExecIf(“PJSIP/121-0000000d”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [s@macro-dial-one:38] ExecIf(“PJSIP/121-0000000d”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [s@macro-dial-one:39] GosubIf(“PJSIP/121-0000000d”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [s@macro-dial-one:40] ExecIf(“PJSIP/121-0000000d”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:41] GosubIf(“PJSIP/121-0000000d”, “0?qwait,1()”) in new stack
– Executing [s@macro-dial-one:42] Set(“PJSIP/121-0000000d”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:43] Set(“PJSIP/121-0000000d”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:44] GotoIf(“PJSIP/121-0000000d”, “0?usegoto,1”) in new stack
– Executing [s@macro-dial-one:45] GotoIf(“PJSIP/121-0000000d”, “0?godial”) in new stack
– Executing [s@macro-dial-one:46] Gosub(“PJSIP/121-0000000d”, “sub-presencestate-display,s,1(125)”) in new stack
– Executing [s@sub-presencestate-display:1] Goto(“PJSIP/121-0000000d”, “state-not_set,1”) in new stack
– Goto (sub-presencestate-display,state-not_set,1)
– Executing [state-not_set@sub-presencestate-display:1] Set(“PJSIP/121-0000000d”, “PRESENCESTATE_DISPLAY=”) in new stack
– Executing [state-not_set@sub-presencestate-display:2] Return(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-dial-one:47] Set(“PJSIP/121-0000000d”, “CONNECTEDLINE(name,i)=Jack Sutton”) in new stack
– Executing [s@macro-dial-one:48] Set(“PJSIP/121-0000000d”, “CONNECTEDLINE(num)=125”) in new stack
– Executing [s@macro-dial-one:49] Set(“PJSIP/121-0000000d”, “D_OPTIONS=HhTtrI”) in new stack
– Executing [s@macro-dial-one:50] Macro(“PJSIP/121-0000000d”, “dialout-one-predial-hook,”) in new stack
– Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-dial-one:51] ExecIf(“PJSIP/121-0000000d”, “0?Set(D_OPTIONS=HhtrII)”) in new stack
– Executing [s@macro-dial-one:52] NoOp(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-dial-one:53] ExecIf(“PJSIP/121-0000000d”, “0?Set(D_OPTIONS=HhTtrIg)”) in new stack
– Executing [s@macro-dial-one:54] Dial(“PJSIP/121-0000000d”, “PJSIP/125/sip:[email protected]:62349;transport=TLS,15,HhTtrIb(func-apply-sipheaders^s^1)”) in new stack
– PJSIP/125-0000000e Internal Gosub(func-apply-sipheaders,s,1) start
– Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/125-0000000e”, “Applying SIP Headers to channel”) in new stack
– Executing [s@func-apply-sipheaders:2] Set(“PJSIP/125-0000000e”, “SIPHEADERKEYS=”) in new stack
– Executing [s@func-apply-sipheaders:3] ExecIf(“PJSIP/125-0000000e”, “0?Set(Rheader=1)”) in new stack
– Executing [s@func-apply-sipheaders:4] While(“PJSIP/125-0000000e”, “0”) in new stack
– Jumping to priority 8
– Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/125-0000000e”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
– Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/125-0000000e”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
– Executing [s@func-apply-sipheaders:11] Return(“PJSIP/125-0000000e”, “”) in new stack
== Spawn extension (from-internal, 125, 1) exited non-zero on ‘PJSIP/125-0000000e’
– PJSIP/125-0000000e Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
– Called PJSIP/125/sip:[email protected]:62349;transport=TLS
== Using SIP RTP Audio TOS bits 184
== Using SIP RTP Audio CoS mark 5
– Connected line update to PJSIP/121-0000000d prevented.
== Everyone is busy/congested at this time (1:0/0/1)
– Executing [s@macro-dial-one:55] ExecIf(“PJSIP/121-0000000d”, “0?MacroExit()”) in new stack
– Executing [s@macro-dial-one:56] ExecIf(“PJSIP/121-0000000d”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [s@macro-dial-one:57] GosubIf(“PJSIP/121-0000000d”, “0?s-CHANUNAVAIL,1()”) in new stack
– Executing [s@macro-dial-one:58] MacroExit(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-exten-vm:15] Set(“PJSIP/121-0000000d”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:16] GosubIf(“PJSIP/121-0000000d”, “0?docfu,1()”) in new stack
– Executing [s@macro-exten-vm:17] GosubIf(“PJSIP/121-0000000d”, “0?docfb,1()”) in new stack
– Executing [s@macro-exten-vm:18] Set(“PJSIP/121-0000000d”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:19] ExecIf(“PJSIP/121-0000000d”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:20] GotoIf(“PJSIP/121-0000000d”, “0?s-CHANUNAVAIL,1”) in new stack
– Executing [s@macro-exten-vm:21] Macro(“PJSIP/121-0000000d”, “vm,125,CHANUNAVAIL,”) in new stack
– Executing [s@macro-vm:1] Macro(“PJSIP/121-0000000d”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/121-0000000d”, “TOUCH_MONITOR=1544208882.13”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/121-0000000d”, “AMPUSER=121”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/121-0000000d”, “16?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [s@macro-user-callerid:15] NoOp(“PJSIP/121-0000000d”, “Macro Depth is 3”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/121-0000000d”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,17)
– Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/121-0000000d”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,35)
– Executing [s@macro-user-callerid:35] Set(“PJSIP/121-0000000d”, “CALLERID(number)=121”) in new stack
– Executing [s@macro-user-callerid:36] Set(“PJSIP/121-0000000d”, “CALLERID(name)=Asher Toporovsky”) in new stack
– Executing [s@macro-user-callerid:37] GotoIf(“PJSIP/121-0000000d”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:38] Set(“PJSIP/121-0000000d”, “CDR(cnam)=Asher Toporovsky”) in new stack
– Executing [s@macro-user-callerid:39] Set(“PJSIP/121-0000000d”, “CDR(cnum)=121”) in new stack
– Executing [s@macro-user-callerid:40] Set(“PJSIP/121-0000000d”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-vm:2] Set(“PJSIP/121-0000000d”, “VMGAIN=”) in new stack
– Executing [s@macro-vm:3] Macro(“PJSIP/121-0000000d”, “blkvm-check,”) in new stack
– Executing [s@macro-blkvm-check:1] Set(“PJSIP/121-0000000d”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-check:2] ExecIf(“PJSIP/121-0000000d”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
– Executing [s@macro-blkvm-check:3] MacroExit(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s@macro-vm:4] GotoIf(“PJSIP/121-0000000d”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] Set(“PJSIP/121-0000000d”, “MEXTEN=125”) in new stack
– Executing [vmx@macro-vm:2] Set(“PJSIP/121-0000000d”, “MMODE=CHANUNAVAIL”) in new stack
– Executing [vmx@macro-vm:3] Set(“PJSIP/121-0000000d”, “RETVM=”) in new stack
– Executing [vmx@macro-vm:4] Set(“PJSIP/121-0000000d”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:5] Macro(“PJSIP/121-0000000d”, “get-vmcontext,125”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“PJSIP/121-0000000d”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/121-0000000d”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/121-0000000d”, “”) in new stack
– Executing [vmx@macro-vm:6] Set(“PJSIP/121-0000000d”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:7] NoOp(“PJSIP/121-0000000d”, “MODE IS: unavail”) in new stack
– Executing [vmx@macro-vm:8] GotoIf(“PJSIP/121-0000000d”, “1?chknomsg”) in new stack
– Goto (macro-vm,vmx,10)
– Executing [vmx@macro-vm:10] GotoIf(“PJSIP/121-0000000d”, “0?s-CHANUNAVAIL,1”) in new stack
– Executing [vmx@macro-vm:11] GotoIf(“PJSIP/121-0000000d”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,13)
– Executing [vmx@macro-vm:13] NoOp(“PJSIP/121-0000000d”, "Checking if ext 125 is enabled: ") in new stack
– Executing [vmx@macro-vm:14] GotoIf(“PJSIP/121-0000000d”, “1?s-CHANUNAVAIL,1”) in new stack
– Goto (macro-vm,s-CHANUNAVAIL,1)
– Executing [s-CHANUNAVAIL@macro-vm:1] Macro(“PJSIP/121-0000000d”, “get-vmcontext,125”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“PJSIP/121-0000000d”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/121-0000000d”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/121-0000000d”, “”) in new stack
– Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail(“PJSIP/121-0000000d”, “125@default,u”) in new stack
> 0x7ff2bc31e6a0 – Strict RTP learning after remote address set to: 192.168.2.15:12744
> 0x7ff2bc31e6a0 – Strict RTP qualifying stream type: audio
> 0x7ff2bc31e6a0 – Strict RTP switching source address to 68.195.13.5:44971
– <PJSIP/121-0000000d> Playing ‘/var/spool/asterisk/voicemail/default/125/unavail.slin’ (language ‘en’)
> 0x7ff2bc31e6a0 – Strict RTP learning complete - Locking on source address 68.195.13.5:44971
== Spawn extension (macro-vm, s-CHANUNAVAIL, 2) exited non-zero on ‘PJSIP/121-0000000d’ in macro ‘vm’
== Spawn extension (macro-exten-vm, s, 21) exited non-zero on ‘PJSIP/121-0000000d’ in macro ‘exten-vm’
== Spawn extension (ext-local, 125, 2) exited non-zero on ‘PJSIP/121-0000000d’
– Executing [h@ext-local:1] Macro(“PJSIP/121-0000000d”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/121-0000000d”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/121-0000000d”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“PJSIP/121-0000000d”, " monior file= ") in new stack
– Executing [s@macro-hangupcall:5] AGI(“PJSIP/121-0000000d”, “attendedtransfer-rec-restart.php,”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
– <PJSIP/121-0000000d>AGI Script attendedtransfer-rec-restart.php completed, returning 0
– Executing [s@macro-hangupcall:6] Hangup(“PJSIP/121-0000000d”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/121-0000000d’ in macro ‘hangupcall’
== Spawn extension (ext-local, h, 1) exited non-zero on ‘PJSIP/121-0000000d’

I am sure its some minor configuration issue in my PJSIP settings – but for the life of me i cannot zero in on it

If someone can help lend an extra hand to get this working i would really appreciate it

thanks

BlazeStudios · December 7, 2018, 7:04pm

It’s calling the endpoint.

The device didn’t answer the call for some reason. Either it was really busy or it has DND turned on.

hardocp · December 7, 2018, 7:38pm

Tom,

thanks for the quick reply

Just checked several of the endpoints and all they are neither busy nor have DND turned on

any other ideas?

Any place else i should check?

hardocp · December 7, 2018, 7:52pm

I just noticed that after a short period of time all my remote endpoints have become unavailable?

Example:

Endpoint: 121/121 Unavailable 0 of inf
InAuth: 121-auth/121
Aor: 121 1
Contact: 121/sip:[email protected]:47329;transport=TL c0eea25396 Unavail nan
Transport: 0.0.0.0-tls tls 3 96 0.0.0.0:5061

Could this be the source of the issue?

I would also add – that as far as i can tell even though asterisk says the endpoints are not available – they seem to be connected and work fine – i i can make outbound calls – echo test etc…

Stewart1 · December 7, 2018, 8:11pm

You could check this by dialing the echo test from an extension (just wait for the announcement to start), hanging up and then calling that extension from another.

If that works, the router (at the extension site) could be timing out the connection; try setting a short registration expiry in the client, e.g. 60 seconds.

hardocp · December 7, 2018, 8:28pm

Echo test works

121 shows unavailable (if i reboot the phone this endpoint becomes available again)

Endpoint: 121/121 Unavailable 0 of inf
InAuth: 121-auth/121
Aor: 121 1
Contact: 121/sip:[email protected]:47329;transport=TL c0eea25396 Unavail nan
Transport: 0.0.0.0-tls tls 3 96 0.0.0.0:5061

After echo test call from another extension goes straight to VM

Im sure its some minor config issue – just need to get my hands on it

– Executing [*43@from-internal:1] Set(“PJSIP/121-0000001a”, “CONNECTEDLINE(name-charset,i)=utf8”) in new stack
– Executing [*43@from-internal:2] Set(“PJSIP/121-0000001a”, “CONNECTEDLINE(name,i)=Echo Test”) in new stack
– Executing [*43@from-internal:3] Set(“PJSIP/121-0000001a”, “CONNECTEDLINE(num,i)=*43”) in new stack
– Executing [*43@from-internal:4] Answer(“PJSIP/121-0000001a”, “”) in new stack
> 0x7ff2bc4436a0 – Strict RTP learning after remote address set to: 192.168.2.15:12766
> 0x7ff2bc4436a0 – Strict RTP qualifying stream type: audio
> 0x7ff2bc4436a0 – Strict RTP switching source address to 68.195.13.5:15269
– Executing [*43@from-internal:5] Macro(“PJSIP/121-0000001a”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/121-0000001a”, “TOUCH_MONITOR=1544214219.26”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/121-0000001a”, “AMPUSER=121”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/121-0000001a”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/121-0000001a”, “1?Set(REALCALLERIDNUM=121)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“PJSIP/121-0000001a”, “AMPUSER=121”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/121-0000001a”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“PJSIP/121-0000001a”, “AMPUSERCIDNAME=Asher Toporovsky”) in new stack
– Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/121-0000001a”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
– Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/121-0000001a”, “0?report”) in new stack
– Executing [s@macro-user-callerid:10] Set(“PJSIP/121-0000001a”, “AMPUSERCID=121”) in new stack
– Executing [s@macro-user-callerid:11] Set(“PJSIP/121-0000001a”, “__DIAL_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-user-callerid:12] Set(“PJSIP/121-0000001a”, “CALLERID(all)=“Asher Toporovsky” <121>”) in new stack
– Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/121-0000001a”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/121-0000001a”, “0?Set(GROUP(concurrency_limit)=121)”) in new stack
– Executing [s@macro-user-callerid:15] NoOp(“PJSIP/121-0000001a”, “Macro Depth is 1”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/121-0000001a”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,17)
– Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/121-0000001a”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:18] Set(“PJSIP/121-0000001a”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/121-0000001a”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,35)
– Executing [s@macro-user-callerid:35] Set(“PJSIP/121-0000001a”, “CALLERID(number)=121”) in new stack
– Executing [s@macro-user-callerid:36] Set(“PJSIP/121-0000001a”, “CALLERID(name)=Asher Toporovsky”) in new stack
– Executing [s@macro-user-callerid:37] GotoIf(“PJSIP/121-0000001a”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:38] Set(“PJSIP/121-0000001a”, “CDR(cnam)=Asher Toporovsky”) in new stack
– Executing [s@macro-user-callerid:39] Set(“PJSIP/121-0000001a”, “CDR(cnum)=121”) in new stack
– Executing [s@macro-user-callerid:40] Set(“PJSIP/121-0000001a”, “CHANNEL(language)=en”) in new stack
– Executing [*43@from-internal:6] Wait(“PJSIP/121-0000001a”, “1”) in new stack
– Executing [*43@from-internal:7] BackGround(“PJSIP/121-0000001a”, “demo-echotest,app-echo-test-echo”) in new stack
– <PJSIP/121-0000001a> Playing ‘demo-echotest.ulaw’ (language ‘en’)
> 0x7ff2bc4436a0 – Strict RTP learning complete - Locking on source address 68.195.13.5:15269
== Spawn extension (from-internal, *43, 7) exited non-zero on ‘PJSIP/121-0000001a’
– Executing [h@from-internal:1] Macro(“PJSIP/121-0000001a”, “hangupcall”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/121-0000001a”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/121-0000001a”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“PJSIP/121-0000001a”, " monior file= ") in new stack
– Executing [s@macro-hangupcall:5] AGI(“PJSIP/121-0000001a”, “attendedtransfer-rec-restart.php,”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
– <PJSIP/121-0000001a>AGI Script attendedtransfer-rec-restart.php completed, returning 0
– Executing [s@macro-hangupcall:6] Hangup(“PJSIP/121-0000001a”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/121-0000001a’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/121-0000001a’
== Setting global variable ‘SIPDOMAIN’ to ‘teaneckpbx.toporovsky.com’
== Using SIP RTP Audio TOS bits 184
== Using SIP RTP Audio CoS mark 5
– Executing [121@from-internal:1] GotoIf(“PJSIP/118-0000001b”, “1?ext-local,121,1:followme-check,121,1”) in new stack
– Goto (ext-local,121,1)
– Executing [121@ext-local:1] Set(“PJSIP/118-0000001b”, “__RINGTIMER=15”) in new stack
– Executing [121@ext-local:2] Macro(“PJSIP/118-0000001b”, “exten-vm,121,121,0,0,0”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“PJSIP/118-0000001b”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/118-0000001b”, “TOUCH_MONITOR=1544214258.27”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/118-0000001b”, “AMPUSER=118”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/118-0000001b”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/118-0000001b”, “1?Set(REALCALLERIDNUM=118)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“PJSIP/118-0000001b”, “AMPUSER=118”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/118-0000001b”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“PJSIP/118-0000001b”, “AMPUSERCIDNAME=Rinaldo Toporovsky”) in new stack
– Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/118-0000001b”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
– Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/118-0000001b”, “0?report”) in new stack
– Executing [s@macro-user-callerid:10] Set(“PJSIP/118-0000001b”, “AMPUSERCID=118”) in new stack
– Executing [s@macro-user-callerid:11] Set(“PJSIP/118-0000001b”, “__DIAL_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-user-callerid:12] Set(“PJSIP/118-0000001b”, “CALLERID(all)=“Rinaldo Toporovsky” <118>”) in new stack
– Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/118-0000001b”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/118-0000001b”, “0?Set(GROUP(concurrency_limit)=118)”) in new stack
– Executing [s@macro-user-callerid:15] NoOp(“PJSIP/118-0000001b”, “Macro Depth is 2”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/118-0000001b”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,17)
– Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/118-0000001b”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:18] Set(“PJSIP/118-0000001b”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/118-0000001b”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,35)
– Executing [s@macro-user-callerid:35] Set(“PJSIP/118-0000001b”, “CALLERID(number)=118”) in new stack
– Executing [s@macro-user-callerid:36] Set(“PJSIP/118-0000001b”, “CALLERID(name)=Rinaldo Toporovsky”) in new stack
– Executing [s@macro-user-callerid:37] GotoIf(“PJSIP/118-0000001b”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:38] Set(“PJSIP/118-0000001b”, “CDR(cnam)=Rinaldo Toporovsky”) in new stack
– Executing [s@macro-user-callerid:39] Set(“PJSIP/118-0000001b”, “CDR(cnum)=118”) in new stack
– Executing [s@macro-user-callerid:40] Set(“PJSIP/118-0000001b”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-exten-vm:2] Set(“PJSIP/118-0000001b”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“PJSIP/118-0000001b”, “__EXTTOCALL=121”) in new stack
– Executing [s@macro-exten-vm:4] Set(“PJSIP/118-0000001b”, “__PICKUPMARK=121”) in new stack
– Executing [s@macro-exten-vm:5] Set(“PJSIP/118-0000001b”, “RT=15”) in new stack
– Executing [s@macro-exten-vm:6] Gosub(“PJSIP/118-0000001b”, “sub-record-check,s,1(exten,121,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“PJSIP/118-0000001b”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“PJSIP/118-0000001b”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“PJSIP/118-0000001b”, “NOW=1544214258”) in new stack
– Executing [s@sub-record-check:4] Set(“PJSIP/118-0000001b”, “__DAY=07”) in new stack
– Executing [s@sub-record-check:5] Set(“PJSIP/118-0000001b”, “__MONTH=12”) in new stack
– Executing [s@sub-record-check:6] Set(“PJSIP/118-0000001b”, “__YEAR=2018”) in new stack
– Executing [s@sub-record-check:7] Set(“PJSIP/118-0000001b”, “__TIMESTR=20181207-152418”) in new stack
– Executing [s@sub-record-check:8] Set(“PJSIP/118-0000001b”, “__FROMEXTEN=118”) in new stack
– Executing [s@sub-record-check:9] Set(“PJSIP/118-0000001b”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“PJSIP/118-0000001b”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“PJSIP/118-0000001b”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“PJSIP/118-0000001b”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“PJSIP/118-0000001b”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“PJSIP/118-0000001b”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“PJSIP/118-0000001b”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] NoOp(“PJSIP/118-0000001b”, “Exten Recording Check between 118 and 121”) in new stack
– Executing [exten@sub-record-check:2] Set(“PJSIP/118-0000001b”, “CALLTYPE=internal”) in new stack
– Executing [exten@sub-record-check:3] ExecIf(“PJSIP/118-0000001b”, “0?Set(CALLTYPE=)”) in new stack
– Executing [exten@sub-record-check:4] Set(“PJSIP/118-0000001b”, “CALLEE=dontcare”) in new stack
– Executing [exten@sub-record-check:5] ExecIf(“PJSIP/118-0000001b”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [exten@sub-record-check:6] GotoIf(“PJSIP/118-0000001b”, “0?callee”) in new stack
– Executing [exten@sub-record-check:7] GotoIf(“PJSIP/118-0000001b”, “1?caller”) in new stack
– Goto (sub-record-check,exten,13)
– Executing [exten@sub-record-check:13] Set(“PJSIP/118-0000001b”, “RECMODE=dontcare”) in new stack
– Executing [exten@sub-record-check:14] ExecIf(“PJSIP/118-0000001b”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:15] ExecIf(“PJSIP/118-0000001b”, “1?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:16] Gosub(“PJSIP/118-0000001b”, “recordcheck,1(dontcare,internal,121)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/118-0000001b”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/118-0000001b”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“PJSIP/118-0000001b”, “”) in new stack
– Executing [exten@sub-record-check:17] Return(“PJSIP/118-0000001b”, “”) in new stack
– Executing [s@macro-exten-vm:7] GotoIf(“PJSIP/118-0000001b”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,13)
– Executing [s@macro-exten-vm:13] GosubIf(“PJSIP/118-0000001b”, “0?clrheader,1()”) in new stack
– Executing [s@macro-exten-vm:14] Macro(“PJSIP/118-0000001b”, “dial-one,15,HhTtr,121”) in new stack
– Executing [s@macro-dial-one:1] Set(“PJSIP/118-0000001b”, “DEXTEN=121”) in new stack
– Executing [s@macro-dial-one:2] ExecIf(“PJSIP/118-0000001b”, “0?Set(__EXTTOCALL=121)”) in new stack
– Executing [s@macro-dial-one:3] Set(“PJSIP/118-0000001b”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“PJSIP/118-0000001b”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:5] GosubIf(“PJSIP/118-0000001b”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:6] GotoIf(“PJSIP/118-0000001b”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,9)
– Executing [s@macro-dial-one:9] GotoIf(“PJSIP/118-0000001b”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:10] GotoIf(“PJSIP/118-0000001b”, “0?continue”) in new stack
– Executing [s@macro-dial-one:11] Set(“PJSIP/118-0000001b”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:12] GotoIf(“PJSIP/118-0000001b”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [s@macro-dial-one:24] GotoIf(“PJSIP/118-0000001b”, “0?next3:continue”) in new stack
– Goto (macro-dial-one,s,26)
– Executing [s@macro-dial-one:26] GotoIf(“PJSIP/118-0000001b”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:27] GosubIf(“PJSIP/118-0000001b”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“PJSIP/118-0000001b”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“PJSIP/118-0000001b”, “DEVICES=121”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/118-0000001b”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/118-0000001b”, “0?Set(DEVICES=21)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“PJSIP/118-0000001b”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“PJSIP/118-0000001b”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“PJSIP/118-0000001b”, “THISDIAL=PJSIP/121”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“PJSIP/118-0000001b”, “1?zap2dahdi,1()”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“PJSIP/118-0000001b”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“PJSIP/118-0000001b”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“PJSIP/118-0000001b”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“PJSIP/118-0000001b”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“PJSIP/118-0000001b”, “THISPART2=PJSIP/121”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“PJSIP/118-0000001b”, “0?Set(THISPART2=DAHDIIP/121)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“PJSIP/118-0000001b”, “NEWDIAL=PJSIP/121&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“PJSIP/118-0000001b”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“PJSIP/118-0000001b”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“PJSIP/118-0000001b”, “THISDIAL=PJSIP/121”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“PJSIP/118-0000001b”, “”) in new stack
– Executing [dstring@macro-dial-one:9] GotoIf(“PJSIP/118-0000001b”, “0?docheck”) in new stack
– Executing [dstring@macro-dial-one:10] NoOp(“PJSIP/118-0000001b”, “Debug: Found PJSIP Destination PJSIP/121”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“PJSIP/118-0000001b”, “0?doset”) in new stack
– Executing [dstring@macro-dial-one:12] NoOp(“PJSIP/118-0000001b”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
– Executing [dstring@macro-dial-one:13] Set(“PJSIP/118-0000001b”, “THISDIAL=”) in new stack
– Executing [dstring@macro-dial-one:14] ExecIf(“PJSIP/118-0000001b”, “1?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
– Executing [dstring@macro-dial-one:15] GotoIf(“PJSIP/118-0000001b”, “1?skipset”) in new stack
– Goto (macro-dial-one,dstring,17)
– Executing [dstring@macro-dial-one:17] Set(“PJSIP/118-0000001b”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:18] GotoIf(“PJSIP/118-0000001b”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:19] ExecIf(“PJSIP/118-0000001b”, “1?Return()”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“PJSIP/118-0000001b”, “1?nodial”) in new stack
– Goto (macro-dial-one,s,59)
– Executing [s@macro-dial-one:59] NoOp(“PJSIP/118-0000001b”, “”) in new stack
– Executing [s@macro-dial-one:60] ExecIf(“PJSIP/118-0000001b”, “0?Set(DIALSTATUS=NOANSWER)”) in new stack
– Executing [s@macro-dial-one:61] NoOp(“PJSIP/118-0000001b”, “Returned from dial-one with nothing to call and DIALSTATUS: CHANUNAVAIL”) in new stack
– Executing [s@macro-dial-one:62] MacroExit(“PJSIP/118-0000001b”, “”) in new stack
– Executing [s@macro-exten-vm:15] Set(“PJSIP/118-0000001b”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:16] GosubIf(“PJSIP/118-0000001b”, “0?docfu,1()”) in new stack
– Executing [s@macro-exten-vm:17] GosubIf(“PJSIP/118-0000001b”, “0?docfb,1()”) in new stack
– Executing [s@macro-exten-vm:18] Set(“PJSIP/118-0000001b”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:19] ExecIf(“PJSIP/118-0000001b”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:20] GotoIf(“PJSIP/118-0000001b”, “0?s-CHANUNAVAIL,1”) in new stack
– Executing [s@macro-exten-vm:21] Macro(“PJSIP/118-0000001b”, “vm,121,CHANUNAVAIL,”) in new stack
– Executing [s@macro-vm:1] Macro(“PJSIP/118-0000001b”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/118-0000001b”, “TOUCH_MONITOR=1544214258.27”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/118-0000001b”, “AMPUSER=118”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/118-0000001b”, “18?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [s@macro-user-callerid:15] NoOp(“PJSIP/118-0000001b”, “Macro Depth is 3”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/118-0000001b”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,17)
– Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/118-0000001b”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,35)
– Executing [s@macro-user-callerid:35] Set(“PJSIP/118-0000001b”, “CALLERID(number)=118”) in new stack
– Executing [s@macro-user-callerid:36] Set(“PJSIP/118-0000001b”, “CALLERID(name)=Rinaldo Toporovsky”) in new stack
– Executing [s@macro-user-callerid:37] GotoIf(“PJSIP/118-0000001b”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:38] Set(“PJSIP/118-0000001b”, “CDR(cnam)=Rinaldo Toporovsky”) in new stack
– Executing [s@macro-user-callerid:39] Set(“PJSIP/118-0000001b”, “CDR(cnum)=118”) in new stack
– Executing [s@macro-user-callerid:40] Set(“PJSIP/118-0000001b”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-vm:2] Set(“PJSIP/118-0000001b”, “VMGAIN=”) in new stack
– Executing [s@macro-vm:3] Macro(“PJSIP/118-0000001b”, “blkvm-check,”) in new stack
– Executing [s@macro-blkvm-check:1] Set(“PJSIP/118-0000001b”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-check:2] ExecIf(“PJSIP/118-0000001b”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
– Executing [s@macro-blkvm-check:3] MacroExit(“PJSIP/118-0000001b”, “”) in new stack
– Executing [s@macro-vm:4] GotoIf(“PJSIP/118-0000001b”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] Set(“PJSIP/118-0000001b”, “MEXTEN=121”) in new stack
– Executing [vmx@macro-vm:2] Set(“PJSIP/118-0000001b”, “MMODE=CHANUNAVAIL”) in new stack
– Executing [vmx@macro-vm:3] Set(“PJSIP/118-0000001b”, “RETVM=”) in new stack
– Executing [vmx@macro-vm:4] Set(“PJSIP/118-0000001b”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:5] Macro(“PJSIP/118-0000001b”, “get-vmcontext,121”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“PJSIP/118-0000001b”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/118-0000001b”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/118-0000001b”, “”) in new stack
– Executing [vmx@macro-vm:6] Set(“PJSIP/118-0000001b”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:7] NoOp(“PJSIP/118-0000001b”, “MODE IS: unavail”) in new stack
– Executing [vmx@macro-vm:8] GotoIf(“PJSIP/118-0000001b”, “1?chknomsg”) in new stack
– Goto (macro-vm,vmx,10)
– Executing [vmx@macro-vm:10] GotoIf(“PJSIP/118-0000001b”, “0?s-CHANUNAVAIL,1”) in new stack
– Executing [vmx@macro-vm:11] GotoIf(“PJSIP/118-0000001b”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,13)
– Executing [vmx@macro-vm:13] NoOp(“PJSIP/118-0000001b”, "Checking if ext 121 is enabled: ") in new stack
– Executing [vmx@macro-vm:14] GotoIf(“PJSIP/118-0000001b”, “1?s-CHANUNAVAIL,1”) in new stack
– Goto (macro-vm,s-CHANUNAVAIL,1)
– Executing [s-CHANUNAVAIL@macro-vm:1] Macro(“PJSIP/118-0000001b”, “get-vmcontext,121”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“PJSIP/118-0000001b”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/118-0000001b”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/118-0000001b”, “”) in new stack
– Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail(“PJSIP/118-0000001b”, “121@default,u”) in new stack
> 0x7ff2bc4436a0 – Strict RTP learning after remote address set to: 192.168.2.16:12762
> 0x7ff2bc4436a0 – Strict RTP qualifying stream type: audio
> 0x7ff2bc4436a0 – Strict RTP switching source address to 68.195.13.5:8452
– <PJSIP/118-0000001b> Playing ‘/var/spool/asterisk/voicemail/default/121/unavail.slin’ (language ‘en’)
== Spawn extension (macro-vm, s-CHANUNAVAIL, 2) exited non-zero on ‘PJSIP/118-0000001b’ in macro ‘vm’
== Spawn extension (macro-exten-vm, s, 21) exited non-zero on ‘PJSIP/118-0000001b’ in macro ‘exten-vm’
== Spawn extension (ext-local, 121, 2) exited non-zero on ‘PJSIP/118-0000001b’
– Executing [h@ext-local:1] Macro(“PJSIP/118-0000001b”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/118-0000001b”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/118-0000001b”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“PJSIP/118-0000001b”, " monior file= ") in new stack
– Executing [s@macro-hangupcall:5] AGI(“PJSIP/118-0000001b”, “attendedtransfer-rec-restart.php,”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
– <PJSIP/118-0000001b>AGI Script attendedtransfer-rec-restart.php completed, returning 0
– Executing [s@macro-hangupcall:6] Hangup(“PJSIP/118-0000001b”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/118-0000001b’ in macro ‘hangupcall’
== Spawn extension (ext-local, h, 1) exited non-zero on ‘PJSIP/118-0000001b’

BlazeStudios · December 7, 2018, 8:40pm

You have a NAT problem. Making an outgoing call or making the phone re-register all initiate new outgoing requests in your router/firewall. If you let the phone sit for a while you’ll start to see your issue again because NAT.

What type of router/firewall is in front of the phones?

hardocp · December 7, 2018, 8:44pm

The endpoints are behind a PFSENSE router

BlazeStudios · December 7, 2018, 8:47pm

Pretty much says it all about PFSENSE. Make sure the “SIP Helpers” are off and the port randomization isn’t happening.

hardocp · December 9, 2018, 5:17pm

So i have done a little more work on this issue and i think the issue must be someplace else and not with PFSENSE

the reason i am thinking this is due to the fact that i have an almost identical network setup with an almost identical VM which is working fine –

At the working site I have a remote phone behind a PFSENSE – i have made no changes to the PFSENSE at all – and the phone registers, stays connected and rings when called just fine

The only difference between the two sites is the one site that is working has a grandstream gxp2135 – at the non working site I have all Yealink Phones –

Again the Yealinks – do register with Asterisk – however they become unavailable in the PJSIP registry after a few minutes of registration, and go straight to VM when an inbound call or extension to extension call is made?

A few more data points – on the non working site – with the Yealinks – i am able to complete and echo test from all extensions *43 without issue – I am also able to place outbound calls – without issue

I also went into general sip settings on the asterisk VM and under local networks and added the ip range for the local LAN where the the Yealinks connect (in testing this made a difference on the grandstream network – ie when the local LAN on which the phones are connected was not listed i would also get a straight to VM issue)

Its just the inbound thats the issue (both external and extension to extension)

Any ideas if this is a config issue with the Yealink vs the Grandstream or should i look someplace else?

BlazeStudios · December 9, 2018, 6:14pm

So if you reboot these Yealinks, they can accept calls without issue? But after about 15 or 30 minutes they can’t get incoming calls? When they can’t get incoming calls, can they make outgoing calls?

This is showing all the signs of a NAT issue. PFSense is notorious for this. You can say “I have it working in X location but Y doesn’t” that doesn’t mean anything. There are other factors between them, like do they have the same type of Internet connection and modem/CPE at both locations? Because those could also add to the issue.

hardocp · December 9, 2018, 6:48pm

If i reboot the Yealinks the PJSIP status becomes available – but then after 10 minutes or so they become unavailable

That being said even after rebooting the Yealinks and them becoming available again i am unable to receive inbound calls

the error i get it:

Using SIP RTP Audio TOS bits 184
== Using SIP RTP Audio CoS mark 5
– Connected line update to PJSIP/121-00000012 prevented.
== Everyone is busy/congested at this time (1:0/0/1)

Whether the phones are available or unavailable i am able to complete an echo test and make outbound calls

Both sites have the same type of internet connection (they are even the same service provider)

I agree that this looks like a NAT issue – which is why i thought i had it solved when i added the phone LAN net to the SIP settings – but no dice?

Any other ideas where we could look?

arielgrin · December 9, 2018, 11:07pm

Have you tried pfSense recommendations for SIP traffic?

https://www.netgate.com/docs/pfsense/nat/configuring-nat-for-voip-phones.html

hardocp · December 10, 2018, 12:43am

Did not seem to help (assuming i set it up correctly – the instructions are a little ambiguous)

system · December 17, 2018, 12:43am

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.