Zoiper and Freepbx 14 - No internal calls

Hi There.

I have a Freepbx v14 Server based in Tamworth UK.
I am somewhere in Scotland using Zioper.

Zoiper is registered and I can make calls to my mobile and get audio fine.

However, when i try to make internal calls my call is rejected.

Does anyone ahve any ideas as to what to check?
I’m using PJSIP.

Many thanks

https://sangomakb.atlassian.net/wiki/spaces/SS/pages/31162494/Providing+Great+Debug

Hi @Si_K
My understanding, yours Outbound calls from Zoiper (softphone) calls are not working to out. My suggestion is try to give us more details how to @david55 suggested you. We need to know your FreePBX some setups such as Outbound Route and Asterisk Logs will give to us a bit more details.

Hi.
I now hove more info.

FreePBX server:
PBX version 14.0.16.11
System Version 12.7.4-1804-2.sng7
Asterisk Version 13.19.1

Ports SIP 5060 and RTP 10000 - 20000 are forwarded to the PBX on 10.0.0.200

I have Zoiper5 on windows 11 in Scotland.

I can register the extension 507 and then phone my mobile phone and all works through the PBX. However, Zoiper5 doesn’t realise that the call has ended.

The big issue is - when I try to call extensions on the PBX, such as 405, I get the call rejected and Zoiper5 throws up a 503 error.

The console output on astrisk:

[root@freepbx ~]# tail -f /var/log/asterisk/full
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/spitfire-00006846", "0?return") in new stack
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/spitfire-00006846", "__CRM_HANGUP=1") in new stack
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/spitfire-00006846", "sangomacrm.agi") in new stack
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] res_agi.c: <PJSIP/spitfire-00006846>AGI Script sangomacrm.agi completed, returning 0
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/spitfire-00006846", "") in new stack
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] app_stack.c: Spawn extension (ext-group, h, 1) exited non-zero on 'PJSIP/spitfire-00006846'
[2024-11-18 13:53:27] VERBOSE[29603][C-00002223] app_stack.c: PJSIP/spitfire-00006846 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2024-11-18 13:53:37] VERBOSE[1946] asterisk.c: Remote UNIX connection
[2024-11-18 13:53:37] VERBOSE[30700] asterisk.c: Remote UNIX connection disconnected
[2024-11-18 13:54:02] VERBOSE[21876] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '87.224.2.223'
[2024-11-18 13:54:02] VERBOSE[21876] netsock2.c: Using SIP RTP Audio TOS bits 184
[2024-11-18 13:54:02] VERBOSE[21876] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2024-11-18 13:54:02] VERBOSE[21876] netsock2.c: Using SIP RTP Audio CoS mark 5
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [405@from-internal:1] GotoIf("PJSIP/507-00006850", "1?ext-local,405,1:followme-check,405,1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (ext-local,405,1)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [405@ext-local:1] Set("PJSIP/507-00006850", "__RINGTIMER=20") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [405@ext-local:2] ExecIf("PJSIP/507-00006850", "0?Set(__CWIGNORE=)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [405@ext-local:3] Macro("PJSIP/507-00006850", "exten-vm,novm,405,0,0,0") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:1] Macro("PJSIP/507-00006850", "user-callerid,") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/507-00006850", "TOUCH_MONITOR=1731938042.26868") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/507-00006850", "AMPUSER=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/507-00006850", "HOTDESCKCHAN=507-00006850") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/507-00006850", "HOTDESKEXTEN=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/507-00006850", "HOTDESKCALL=0") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/507-00006850", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/507-00006850", "0?Set(CALLERID(name)=)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/507-00006850", "0?report") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/507-00006850", "1?Set(REALCALLERIDNUM=507)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/507-00006850", "AMPUSER=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/507-00006850", "0?limit") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/507-00006850", "AMPUSERCIDNAME=Simon") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/507-00006850", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/507-00006850", "0?report") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/507-00006850", "AMPUSERCID=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:16] Set("PJSIP/507-00006850", "__DIAL_OPTIONS=HhTtr") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/507-00006850", "CALLERID(all)="Simon" <507>") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:18] Set("PJSIP/507-00006850", "HOTDESCKCHAN=507-00006850") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:19] Set("PJSIP/507-00006850", "HOTDESKEXTEN=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/507-00006850", "HOTDESKCALL=0") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("PJSIP/507-00006850", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("PJSIP/507-00006850", "0?Set(CALLERID(name)=)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/507-00006850", "0?Set(CALLERID(all)="" <  >)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/507-00006850", "0?Set(CUSDIAL=405)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:25] ExecIf("PJSIP/507-00006850", "0?Set(CALLERID(all)="Simon" <507>)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:26] GotoIf("PJSIP/507-00006850", "0?limit") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/507-00006850", "0?Set(GROUP(concurrency_limit)=507)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:28] ExecIf("PJSIP/507-00006850", "0?Set(CHANNEL(language)=)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/507-00006850", "Macro Depth is 2") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/507-00006850", "1?report2:macroerror") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-user-callerid,s,31)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("PJSIP/507-00006850", "0?continue") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:32] ExecIf("PJSIP/507-00006850", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/507-00006850", "__TTL=64") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:34] GotoIf("PJSIP/507-00006850", "1?continue") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-user-callerid,s,50)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/507-00006850", "CALLERID(number)=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/507-00006850", "CALLERID(name)=Simon") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("PJSIP/507-00006850", "0?cnum") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/507-00006850", "CDR(cnam)=Simon") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/507-00006850", "CDR(cnum)=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/507-00006850", "CHANNEL(language)=en") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/507-00006850", "RingGroupMethod=none") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/507-00006850", "__EXTTOCALL=405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/507-00006850", "__PICKUPMARK=405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/507-00006850", "RT=") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("PJSIP/507-00006850", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("PJSIP/507-00006850", "0?Gosub(ext-intercom,*80405,1())") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("PJSIP/507-00006850", "0?ChanSpy(PJSIP/405,q)") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("PJSIP/507-00006850", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("PJSIP/507-00006850", "0?Gosub(ext-intercom,*80405,1())") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("PJSIP/507-00006850", "0?ChanSpy(PJSIP/405,q)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:18] ExecIf("PJSIP/507-00006850", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:19] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] WARNING[30713][C-00002226] chan_sip.c: This function can only be used on SIP channels.
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:20] ExecIf("PJSIP/507-00006850", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:21] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:22] Gosub("PJSIP/507-00006850", "sub-record-check,s,1(exten,405,dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/507-00006850", "0?initialized") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/507-00006850", "__REC_STATUS=INITIALIZED") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/507-00006850", "NOW=1731938042") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/507-00006850", "__DAY=18") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/507-00006850", "__MONTH=11") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/507-00006850", "__YEAR=2024") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/507-00006850", "__TIMESTR=20241118-135402") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/507-00006850", "__FROMEXTEN=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/507-00006850", "__MON_FMT=wav") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/507-00006850", "Recordings initialized") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/507-00006850", "0?Set(ARG3=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/507-00006850", "REC_POLICY_MODE_SAVE=") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/507-00006850", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/507-00006850", "5?checkaction") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/507-00006850", "1?sub-record-check,exten,1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/507-00006850", "Exten Recording Check between 507 and 405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/507-00006850", "CALLTYPE=internal") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:3] ExecIf("PJSIP/507-00006850", "0?Set(CALLTYPE=)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:4] Set("PJSIP/507-00006850", "CALLEE=dontcare") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:5] ExecIf("PJSIP/507-00006850", "0?Set(CALLEE=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:6] GotoIf("PJSIP/507-00006850", "0?callee") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:7] GotoIf("PJSIP/507-00006850", "1?caller") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:13] Set("PJSIP/507-00006850", "RECMODE=dontcare") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:14] Set("PJSIP/507-00006850", "CALLERRECMODE=dontcare") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:15] Set("PJSIP/507-00006850", "CALEERECMODE=dontcare") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:16] GotoIf("PJSIP/507-00006850", "0?processnormal") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:17] ExecIf("PJSIP/507-00006850", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:18] ExecIf("PJSIP/507-00006850", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:19] ExecIf("PJSIP/507-00006850", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:20] ExecIf("PJSIP/507-00006850", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:21] ExecIf("PJSIP/507-00006850", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:22] ExecIf("PJSIP/507-00006850", "1?Set(RECMODE=dontcare)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:23] Gosub("PJSIP/507-00006850", "recordcheck,1(dontcare,internal,405)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/507-00006850", "Starting recording check against dontcare") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/507-00006850", "dontcare") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/507-00006850", "") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [exten@sub-record-check:24] Return("PJSIP/507-00006850", "") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:23] GotoIf("PJSIP/507-00006850", "1?macrodial") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-exten-vm,s,29)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:29] GosubIf("PJSIP/507-00006850", "0?clrheader,1()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:30] Macro("PJSIP/507-00006850", "dial-one,,HhTtr,405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/507-00006850", "DEXTEN=405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:2] Set("PJSIP/507-00006850", "__CRM_SOURCE=507") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:3] ExecIf("PJSIP/507-00006850", "0?Set(__EXTTOCALL=405)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:4] Set("PJSIP/507-00006850", "DIALSTATUS_CW=") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:5] GosubIf("PJSIP/507-00006850", "0?screen,1()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:6] GosubIf("PJSIP/507-00006850", "0?cf,1()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:7] GotoIf("PJSIP/507-00006850", "1?skip1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-dial-one,s,10)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:10] GotoIf("PJSIP/507-00006850", "0?nodial") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:11] GotoIf("PJSIP/507-00006850", "0?continue") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:12] Set("PJSIP/507-00006850", "EXTHASCW=ENABLED") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:13] GotoIf("PJSIP/507-00006850", "0?next1:cwinusebusy") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-dial-one,s,25)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:25] GotoIf("PJSIP/507-00006850", "0?next3:continue") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-dial-one,s,27)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:27] GotoIf("PJSIP/507-00006850", "0?nodial") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:28] GosubIf("PJSIP/507-00006850", "1?dstring,1():dlocal,1()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/507-00006850", "DSTRING=") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/507-00006850", "DEVICES=405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/507-00006850", "0?Return()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/507-00006850", "0?Set(DEVICES=05)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/507-00006850", "LOOPCNT=1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/507-00006850", "ITER=1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/507-00006850", "THISDIAL=PJSIP/405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("PJSIP/507-00006850", "1?zap2dahdi,1()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("PJSIP/507-00006850", "0?Return()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("PJSIP/507-00006850", "NEWDIAL=") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("PJSIP/507-00006850", "LOOPCNT2=1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("PJSIP/507-00006850", "ITER2=1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("PJSIP/507-00006850", "THISPART2=PJSIP/405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("PJSIP/507-00006850", "0?Set(THISPART2=DAHDIIP/405)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("PJSIP/507-00006850", "NEWDIAL=PJSIP/405&") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("PJSIP/507-00006850", "ITER2=2") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("PJSIP/507-00006850", "0?begin2") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("PJSIP/507-00006850", "THISDIAL=PJSIP/405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("PJSIP/507-00006850", "") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("PJSIP/507-00006850", "0?docheck") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("PJSIP/507-00006850", "Debug: Found PJSIP Destination PJSIP/405") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("PJSIP/507-00006850", "0?doset") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("PJSIP/507-00006850", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:13] Set("PJSIP/507-00006850", "THISDIAL=") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf("PJSIP/507-00006850", "1?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("PJSIP/507-00006850", "1?skipset") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-dial-one,dstring,17)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:17] Set("PJSIP/507-00006850", "ITER=2") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("PJSIP/507-00006850", "0?begin") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("PJSIP/507-00006850", "1?Return()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:29] GotoIf("PJSIP/507-00006850", "1?nodial") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-dial-one,s,60)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:60] NoOp("PJSIP/507-00006850", "") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:61] ExecIf("PJSIP/507-00006850", "0?Set(DIALSTATUS=NOANSWER)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:62] NoOp("PJSIP/507-00006850", "Returned from dial-one with nothing to call and DIALSTATUS: CHANUNAVAIL") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-dial-one:63] MacroExit("PJSIP/507-00006850", "") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:31] Set("PJSIP/507-00006850", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:32] GosubIf("PJSIP/507-00006850", "0?docfu,1()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:33] GosubIf("PJSIP/507-00006850", "0?docfb,1()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:34] Set("PJSIP/507-00006850", "DIALSTATUS=CHANUNAVAIL") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:35] ExecIf("PJSIP/507-00006850", "0?MacroExit()") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-exten-vm:36] GotoIf("PJSIP/507-00006850", "1?s-CHANUNAVAIL,1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-exten-vm,s-CHANUNAVAIL,1)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:1] GotoIf("PJSIP/507-00006850", "0?exit,1") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:2] PlayTones("PJSIP/507-00006850", "congestion") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:3] Congestion("PJSIP/507-00006850", "10") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] app_macro.c: Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on 'PJSIP/507-00006850' in macro 'exten-vm'
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Spawn extension (ext-local, 405, 3) exited non-zero on 'PJSIP/507-00006850'
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [h@ext-local:1] Macro("PJSIP/507-00006850", "hangupcall,") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/507-00006850", "1?theend") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/507-00006850", "0?Set(CDR(recordingfile)=)") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/507-00006850", " montior file= ") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/507-00006850", "1?skipagi") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/507-00006850", "") in new stack
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/507-00006850' in macro 'hangupcall'
[2024-11-18 13:54:02] VERBOSE[30713][C-00002226] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/507-00006850'

TIA