FreePBX PJSIP and Cisco 7965G phone

I have a cisco 7965G , trying to register it to version 14 with asterisk 13.18.4.
force-rport =no on the 4extenstio
phone shows registered and I see registration req, and 200 ok in the pcap .however, peer shows this extension is unavailable and can not make call to this extension, any idea why its unavailable in freepbx?

(changing force-rport=yes, makes phone unregistered )

How about a log of a failed call?

… or the failing registration? A SIP DEBUG might also come in really handy.

I am going to put the log in here in blocks it is too bid to past the whole thing.

[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [555587@from-trunk:1] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?ext-local,555587,1:followme-check,555587,1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (ext-local,555587,1)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [555587@ext-local:1] Set(“PJSIP/Ril2gsc-00000016”, “__RINGTIMER=15”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [555587@ext-local:2] Macro(“PJSIP/Ril2gsc-00000016”, “exten-vm,555587,555587,0,0,0”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:1] Macro(“PJSIP/Ril2gsc-00000016”, “user-callerid,”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/Ril2gsc-00000016”, “TOUCH_MONITOR=1531488352.22”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/Ril2gsc-00000016”, “AMPUSER=494434”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?report”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/Ril2gsc-00000016”, “1?Set(REALCALLERIDNUM=494434)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/Ril2gsc-00000016”, “AMPUSER=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?limit”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/Ril2gsc-00000016”, “AMPUSERCIDNAME=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?report”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/Ril2gsc-00000016”, “Macro Depth is 2”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?report2:macroerror”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?continue”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“PJSIP/Ril2gsc-00000016”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:20] Set(“PJSIP/Ril2gsc-00000016”, “__TTL=64”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?continue”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/Ril2gsc-00000016”, “CALLERID(number)=494434”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/Ril2gsc-00000016”, “CALLERID(name)=Piper Phillip”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?cnum”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/Ril2gsc-00000016”, “CDR(cnam)=Piper Phillip”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/Ril2gsc-00000016”, “CDR(cnum)=494434”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/Ril2gsc-00000016”, “CHANNEL(language)=en”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:2] Set(“PJSIP/Ril2gsc-00000016”, “RingGroupMethod=none”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:3] Set(“PJSIP/Ril2gsc-00000016”, “__EXTTOCALL=555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:4] Set(“PJSIP/Ril2gsc-00000016”, “__PICKUPMARK=555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:5] Set(“PJSIP/Ril2gsc-00000016”, “RT=15”) in new stack
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Macro(vm,555587,DIRECTDIAL,)”) in new stack
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?MacroExit()”) in new stack
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:8] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Gosub(ext-intercom,*80555587,1())”) in new stack
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:9] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?MacroExit()”) in new stack
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:10] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?ChanSpy(PJSIP/555587,q)”) in new stack
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:11] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?MacroExit()”) in new stack
[2018-07-13 08:25:52] WARNING[503][C-0000000e] chan_sip.c: This function can only be used on SIP channels.
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:12] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Macro(vm,555587,DIRECTDIAL,)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:13] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?MacroExit()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:14] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Gosub(ext-intercom,*80555587,1())”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:15] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?MacroExit()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:16] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?ChanSpy(PJSIP/555587,q)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:17] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?MacroExit()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:18] Gosub(“PJSIP/Ril2gsc-00000016”, “sub-record-check,s,1(exten,555587,dontcare)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?initialized”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/Ril2gsc-00000016”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/Ril2gsc-00000016”, “NOW=1531488352”) in new stack

[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/Ril2gsc-00000016”, “__DAY=13”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/Ril2gsc-00000016”, “__MONTH=07”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/Ril2gsc-00000016”, “__YEAR=2018”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/Ril2gsc-00000016”, “__TIMESTR=20180713-082552”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/Ril2gsc-00000016”, “__FROMEXTEN=494434”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/Ril2gsc-00000016”, “__MON_FMT=wav”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/Ril2gsc-00000016”, “Recordings initialized”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(ARG3=dontcare)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/Ril2gsc-00000016”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/Ril2gsc-00000016”, “5?checkaction”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?sub-record-check,exten,1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:1] NoOp(“PJSIP/Ril2gsc-00000016”, “Exten Recording Check between 494434 and 555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:2] Set(“PJSIP/Ril2gsc-00000016”, “CALLTYPE=internal”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(CALLTYPE=)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:4] Set(“PJSIP/Ril2gsc-00000016”, “CALLEE=dontcare”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(CALLEE=dontcare)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?callee”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:7] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?caller”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:13] Set(“PJSIP/Ril2gsc-00000016”, “RECMODE=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:14] ExecIf(“PJSIP/Ril2gsc-00000016”, “1?Set(RECMODE=dontcare)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:15] ExecIf(“PJSIP/Ril2gsc-00000016”, “1?Set(RECMODE=dontcare)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:16] Gosub(“PJSIP/Ril2gsc-00000016”, “recordcheck,1(dontcare,internal,555587)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/Ril2gsc-00000016”, “Starting recording check against dontcare”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/Ril2gsc-00000016”, “dontcare”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [exten@sub-record-check:17] Return(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:19] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?macrodial”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-exten-vm,s,25)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:25] GosubIf(“PJSIP/Ril2gsc-00000016”, “0?clrheader,1()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:26] Macro(“PJSIP/Ril2gsc-00000016”, “dial-one,15,555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:1] Set(“PJSIP/Ril2gsc-00000016”, “DEXTEN=555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:2] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(__EXTTOCALL=555587)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:3] Set(“PJSIP/Ril2gsc-00000016”, “DIALSTATUS_CW=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:4] GosubIf(“PJSIP/Ril2gsc-00000016”, “0?screen,1()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“PJSIP/Ril2gsc-00000016”, “0?cf,1()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:6] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?skip1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-dial-one,s,9)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:9] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?nodial”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?continue”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:11] Set(“PJSIP/Ril2gsc-00000016”, “EXTHASCW=ENABLED”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:12] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?next1:cwinusebusy”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-dial-one,s,24)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:24] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?next3:continue”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-dial-one,s,26)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:26] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?nodial”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:27] GosubIf(“PJSIP/Ril2gsc-00000016”, “1?dstring,1():dlocal,1()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:1] Set(“PJSIP/Ril2gsc-00000016”, “DSTRING=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:2] Set(“PJSIP/Ril2gsc-00000016”, “DEVICES=555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Return()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(DEVICES=55587)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:5] Set(“PJSIP/Ril2gsc-00000016”, “LOOPCNT=1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:6] Set(“PJSIP/Ril2gsc-00000016”, “ITER=1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:7] Set(“PJSIP/Ril2gsc-00000016”, “THISDIAL=PJSIP/555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“PJSIP/Ril2gsc-00000016”, “1?zap2dahdi,1()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Return()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“PJSIP/Ril2gsc-00000016”, “NEWDIAL=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“PJSIP/Ril2gsc-00000016”, “LOOPCNT2=1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“PJSIP/Ril2gsc-00000016”, “ITER2=1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“PJSIP/Ril2gsc-00000016”, “THISPART2=PJSIP/555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(THISPART2=DAHDIIP/555587)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“PJSIP/Ril2gsc-00000016”, “NEWDIAL=PJSIP/555587&”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“PJSIP/Ril2gsc-00000016”, “ITER2=2”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?begin2”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“PJSIP/Ril2gsc-00000016”, “THISDIAL=PJSIP/555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?docheck”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:10] NoOp(“PJSIP/Ril2gsc-00000016”, “Debug: Found PJSIP Destination PJSIP/555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?doset”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:12] NoOp(“PJSIP/Ril2gsc-00000016”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:13] Set(“PJSIP/Ril2gsc-00000016”, “THISDIAL=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?skipset”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-dial-one,dstring,16)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:16] Set(“PJSIP/Ril2gsc-00000016”, “ITER=2”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?begin”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf(“PJSIP/Ril2gsc-00000016”, “1?Return()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?nodial”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-dial-one,s,59)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:59] NoOp(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:60] ExecIf(“PJSIP/Ril2gsc-00000016”, “1?Set(DIALSTATUS=NOANSWER)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:61] NoOp(“PJSIP/Ril2gsc-00000016”, “Returned from dial-one with nothing to call and DIALSTATUS: NOANSWER”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-dial-one:62] MacroExit(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:27] Set(“PJSIP/Ril2gsc-00000016”, “SV_DIALSTATUS=NOANSWER”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:28] GosubIf(“PJSIP/Ril2gsc-00000016”, “0?docfu,1()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:29] GosubIf(“PJSIP/Ril2gsc-00000016”, “0?docfb,1()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:30] Set(“PJSIP/Ril2gsc-00000016”, “DIALSTATUS=NOANSWER”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:31] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?MacroExit()”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:32] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?s-NOANSWER,1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-exten-vm:33] Macro(“PJSIP/Ril2gsc-00000016”, “vm,555587,NOANSWER,”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-vm:1] Macro(“PJSIP/Ril2gsc-00000016”, “user-callerid,SKIPTTL”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/Ril2gsc-00000016”, “TOUCH_MONITOR=1531488352.22”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/Ril2gsc-00000016”, “AMPUSER=494434”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?report”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(REALCALLERIDNUM=494434)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/Ril2gsc-00000016”, “AMPUSER=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?limit”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/Ril2gsc-00000016”, “AMPUSERCIDNAME=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?report”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/Ril2gsc-00000016”, “Macro Depth is 3”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?report2:macroerror”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?continue”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/Ril2gsc-00000016”, “CALLERID(number)=494434”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/Ril2gsc-00000016”, “CALLERID(name)=Piper Phillip”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?cnum”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/Ril2gsc-00000016”, “CDR(cnam)=Piper Phillip”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/Ril2gsc-00000016”, “CDR(cnum)=494434”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/Ril2gsc-00000016”, “CHANNEL(language)=en”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-vm:2] Set(“PJSIP/Ril2gsc-00000016”, “VMGAIN=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-vm:3] Macro(“PJSIP/Ril2gsc-00000016”, “blkvm-check,”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-blkvm-check:1] Set(“PJSIP/Ril2gsc-00000016”, “GOSUB_RETVAL=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-vm:4] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?vmx,1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-vm,vmx,1)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:1] Set(“PJSIP/Ril2gsc-00000016”, “MEXTEN=555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:2] Set(“PJSIP/Ril2gsc-00000016”, “MMODE=NOANSWER”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:3] Set(“PJSIP/Ril2gsc-00000016”, “RETVM=”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:4] Set(“PJSIP/Ril2gsc-00000016”, “MODE=unavail”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:5] Macro(“PJSIP/Ril2gsc-00000016”, “get-vmcontext,555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“PJSIP/Ril2gsc-00000016”, “VMCONTEXT=default”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?200:300”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:6] Set(“PJSIP/Ril2gsc-00000016”, “MODE=unavail”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:7] NoOp(“PJSIP/Ril2gsc-00000016”, “MODE IS: unavail”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:8] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?chknomsg”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-vm,vmx,10)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:10] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?s-NOANSWER,1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:11] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?notdirect”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-vm,vmx,13)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:13] NoOp(“PJSIP/Ril2gsc-00000016”, "Checking if ext 555587 is enabled: ") in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [vmx@macro-vm:14] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?s-NOANSWER,1”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro(“PJSIP/Ril2gsc-00000016”, “get-vmcontext,555587”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“PJSIP/Ril2gsc-00000016”, “VMCONTEXT=default”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/Ril2gsc-00000016”, “0?200:300”) in new stack

[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail(“PJSIP/Ril2gsc-00000016”, “555587@default,u”) in new stack
[2018-07-13 08:25:52] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘vm-theperson.ulaw’ (language ‘en’)
[2018-07-13 08:25:54] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘digits/5.ulaw’ (language ‘en’)
[2018-07-13 08:25:55] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘digits/5.ulaw’ (language ‘en’)
[2018-07-13 08:25:56] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘digits/5.ulaw’ (language ‘en’)
[2018-07-13 08:25:56] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘digits/5.ulaw’ (language ‘en’)
[2018-07-13 08:25:57] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘digits/8.ulaw’ (language ‘en’)
[2018-07-13 08:25:58] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘digits/7.ulaw’ (language ‘en’)
[2018-07-13 08:25:59] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘vm-isunavail.ulaw’ (language ‘en’)
[2018-07-13 08:26:00] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘vm-intro.ulaw’ (language ‘en’)
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] file.c: <PJSIP/Ril2gsc-00000016> Playing ‘beep.ulaw’ (language ‘en’)
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app_voicemail.c: Recording the message
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app.c: x=0, open writing: /var/spool/asterisk/voicemail/default/555587/tmp/N300Gx format: wav, 0x7f7ab400a2a0
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app.c: x=1, open writing: /var/spool/asterisk/voicemail/default/555587/tmp/N300Gx format: wav49, 0x7f7ab4002fb0
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app.c: User hung up
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app_voicemail.c: Recording was 0 seconds long but needs to be at least 2 - abandoning
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app_macro.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘PJSIP/Ril2gsc-00000016’ in macro ‘vm’
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app_macro.c: Spawn extension (macro-exten-vm, s, 33) exited non-zero on ‘PJSIP/Ril2gsc-00000016’ in macro ‘exten-vm’
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Spawn extension (ext-local, 555587, 2) exited non-zero on ‘PJSIP/Ril2gsc-00000016’
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Executing [h@ext-local:1] Macro(“PJSIP/Ril2gsc-00000016”, “hangupcall,”) in new stack
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/Ril2gsc-00000016”, “1?theend”) in new stack
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/Ril2gsc-00000016”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/Ril2gsc-00000016”, " monior file= ") in new stack
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-hangupcall:5] AGI(“PJSIP/Ril2gsc-00000016”, “attendedtransfer-rec-restart.php,”) in new stack
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] res_agi.c: <PJSIP/Ril2gsc-00000016>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“PJSIP/Ril2gsc-00000016”, “”) in new stack
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/Ril2gsc-00000016’ in macro ‘hangupcall’
[2018-07-13 08:26:06] VERBOSE[503][C-0000000e] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘PJSIP/Ril2gsc-00000016’

the registration does not fail. the phone is registered. but it shows it as unreachable

this is the output for the Cisco ext from pjsip set logger

<— Transmitting SIP request (638 bytes) to UDP:10.84.88.21:50035 —>
NOTIFY sip:[email protected]:50035 SIP/2.0
Via: SIP/2.0/UDP 10.70.26.10:5060;rport;branch=z9hG4bKPj3d804149-fe41-4237-b254-6f4362f3d910
From: sip:[email protected];tag=ca858c69-39e3-415c-a14d-cdc5e91c2a5c
To: sip:[email protected]
Contact: sip:[email protected]:5060
Call-ID: 9ac49e6b-4a1c-4da7-8703-76bb208c144a
CSeq: 19481 NOTIFY
Subscription-State: terminated
Event: message-summary
Allow-Events: presence, dialog, message-summary, refer
Max-Forwards: 70
User-Agent: FPBX-14.0.3.6(13.18.4)
Content-Type: application/simple-message-summary
Content-Length: 48

we are using PJSIP. and we are having the same issue on freepbx 13 and 14

Check your network settings for all of the devices. Depending on how your network is set up, you may need to turn NAT on for this to work (depends on gateway and netmask settings, of course).

The fix was this :
Extenstion->Advance Tab

rewrite-contact=no ( if its extension is set tp pjsip )
force-rport=no ( for sip and pjsip both )

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