Hosted FreePBX SonicWall no Audio

[cyberdocwi]

Hello,

We have a FreePBX 14 / Asterisk 16 system being hosted on freepbxhosting.com (Cyberlynk). Phones and system work great through my Mikrotik and Linux firewalls, but fail when at my customer’s location that uses a Sonicwall SOHO.

At my customer’s location, the network flows through the SonicWall and out to the FreePBX server, where the phones register, and ring. Using the feature codes for echo testing and telling me the time, and I hear Allison’s system voice just fine. My echo test works great with little latency.

I have seen some writeups on what to do for general SonicWalls, but nothing specific for the SonicWall SOHO. I believe we are running firmware 5.9.1.4 and have done the ALG settings.

THe phones ring, but no audio. There has to be a setting I am missing.

Thanks,

Christian

[Stewart1 (Stewart)]

Confirm that Disable Source Port Remap is set for the NAT policy in question.

Confirm that the phones are not attempting any NAT traversal.

If using chan_sip, confirm that nat=yes for the extensions in question.

If you still have trouble, post a log including SIP trace of a failing call. (I assume that outgoing external calls work fine, as well as calls from a SW extension to e.g. a Mikrotik extension. If that’s not the case, provide more details as to what works and what doesn’t.)

[lgaetz (Lorne Gaetz)]

Happiness With Sonicwalls - It can happen!

Ok - Wasted quite a bit of time this morning with a new configuration we were trying out and I thought I would post it here so that no one else has to waste the same amount of time that I did this morning. First, some general information: For a standard setup with a FreePBX/Asterisk PBX onsite, you will need the following on the Sonicwall: A Port Forwarding rule of 5060-UDP for the Incoming SIP Trunk - Sonicwalls are very AGGRESSIVE about closing that port, so if you use a SIP trunk and you…

[GSnover]

That post of mine is fairly old now, but I just looked through it and it is still accurate - just remember that “Disable Source Port Remap” was only for a specific SIP Trunking provider we were using that used a Broadsoft Switch - we switched providers and have never had to use that setting again.

For phones behind a FAR-End SonicWALL, very little settings are required - just make sure that there are no rules sending the RTP range to somewhere strange - Usually we see this problem when the customer had initially had an On-Premise Asterisk, with the SIP and RTP forwarded to the PBX, and then moved to the Cloud Arrangement and forgot to remove the RTP range from forwarding.

Look under Firewall → Access Rules → Wan to LAN (Custom) and see if there is a rule there re-directing the RTP:

If there is, get rid of it.

[cyberdocwi]

Hello Everyone,
Thank you for the quick response. Here is a debug of one extension calling another. Calls rang fine; no audio:

[2019-07-24 10:02:32] VERBOSE[7768] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘FREEPBX SERVER IP’
[2019-07-24 10:02:32] VERBOSE[7768] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-07-24 10:02:32] VERBOSE[7768] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-07-24 10:02:32] VERBOSE[7768] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [13@from-internal:1] GotoIf(“PJSIP/23-00000122”, “1?ext-local,13,1:followme-check,13,1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (ext-local,13,1)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [13@ext-local:1] Set(“PJSIP/23-00000122”, “__RINGTIMER=15”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [13@ext-local:2] Macro(“PJSIP/23-00000122”, “exten-vm,novm,13,0,0,0”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:1] Macro(“PJSIP/23-00000122”, “user-callerid,”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/23-00000122”, “TOUCH_MONITOR=1563980552.463”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/23-00000122”, “AMPUSER=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/23-00000122”, “0?report”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/23-00000122”, “1?Set(REALCALLERIDNUM=23)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/23-00000122”, “AMPUSER=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/23-00000122”, “0?limit”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/23-00000122”, “AMPUSERCIDNAME=Exam 3”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/23-00000122”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/23-00000122”, “0?report”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/23-00000122”, “AMPUSERCID=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/23-00000122”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/23-00000122”, “CALLERID(all)=“Exam 3” <23>”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/23-00000122”, “0?limit”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/23-00000122”, “0?Set(GROUP(concurrency_limit)=23)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/23-00000122”, “0?Set(CHANNEL(language)=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/23-00000122”, “Macro Depth is 2”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/23-00000122”, “1?report2:macroerror”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/23-00000122”, “0?continue”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“PJSIP/23-00000122”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:20] Set(“PJSIP/23-00000122”, “__TTL=64”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“PJSIP/23-00000122”, “1?continue”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/23-00000122”, “CALLERID(number)=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/23-00000122”, “CALLERID(name)=Exam 3”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/23-00000122”, “0?cnum”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/23-00000122”, “CDR(cnam)=Exam 3”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/23-00000122”, “CDR(cnum)=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/23-00000122”, “CHANNEL(language)=en”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:2] Set(“PJSIP/23-00000122”, “RingGroupMethod=none”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:3] Set(“PJSIP/23-00000122”, “__EXTTOCALL=13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:4] Set(“PJSIP/23-00000122”, “__PICKUPMARK=13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:5] Set(“PJSIP/23-00000122”, “RT=”) in new stack
[2019-07-24 10:02:32] WARNING[25983][C-00000104] chan_sip.c: This function can only be used on SIP channels.
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(“PJSIP/23-00000122”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2019-07-24 10:02:32] WARNING[25983][C-00000104] chan_sip.c: This function can only be used on SIP channels.
[2019-07-24 10:02:32] WARNING[25983][C-00000104] chan_sip.c: This function can only be used on SIP channels.
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(“PJSIP/23-00000122”, “0?MacroExit()”) in new stack
[2019-07-24 10:02:32] WARNING[25983][C-00000104] chan_sip.c: This function can only be used on SIP channels.
[2019-07-24 10:02:32] WARNING[25983][C-00000104] chan_sip.c: This function can only be used on SIP channels.
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:8] ExecIf(“PJSIP/23-00000122”, “0?Gosub(ext-intercom,*8013,1())”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:9] ExecIf(“PJSIP/23-00000122”, “0?MacroExit()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:10] ExecIf(“PJSIP/23-00000122”, “0?ChanSpy(PJSIP/13,q)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:11] ExecIf(“PJSIP/23-00000122”, “0?MacroExit()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:12] ExecIf(“PJSIP/23-00000122”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:13] ExecIf(“PJSIP/23-00000122”, “0?MacroExit()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:14] ExecIf(“PJSIP/23-00000122”, “0?Gosub(ext-intercom,*8013,1())”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:15] ExecIf(“PJSIP/23-00000122”, “0?MacroExit()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:16] ExecIf(“PJSIP/23-00000122”, “0?ChanSpy(PJSIP/13,q)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:17] ExecIf(“PJSIP/23-00000122”, “0?MacroExit()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:18] Gosub(“PJSIP/23-00000122”, “sub-record-check,s,1(exten,13,dontcare)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/23-00000122”, “0?initialized”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/23-00000122”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/23-00000122”, “NOW=1563980552”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/23-00000122”, “__DAY=24”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/23-00000122”, “__MONTH=07”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/23-00000122”, “__YEAR=2019”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/23-00000122”, “__TIMESTR=20190724-100232”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/23-00000122”, “__FROMEXTEN=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/23-00000122”, “__MON_FMT=wav”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/23-00000122”, “Recordings initialized”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/23-00000122”, “0?Set(ARG3=dontcare)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/23-00000122”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/23-00000122”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/23-00000122”, “5?checkaction”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/23-00000122”, “1?sub-record-check,exten,1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:1] NoOp(“PJSIP/23-00000122”, “Exten Recording Check between 23 and 13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:2] Set(“PJSIP/23-00000122”, “CALLTYPE=internal”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“PJSIP/23-00000122”, “0?Set(CALLTYPE=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:4] Set(“PJSIP/23-00000122”, “CALLEE=dontcare”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“PJSIP/23-00000122”, “0?Set(CALLEE=dontcare)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“PJSIP/23-00000122”, “0?callee”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:7] GotoIf(“PJSIP/23-00000122”, “1?caller”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:13] Set(“PJSIP/23-00000122”, “RECMODE=dontcare”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:14] ExecIf(“PJSIP/23-00000122”, “0?Set(RECMODE=dontcare)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:15] ExecIf(“PJSIP/23-00000122”, “1?Set(RECMODE=dontcare)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:16] Gosub(“PJSIP/23-00000122”, “recordcheck,1(dontcare,internal,13)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/23-00000122”, “Starting recording check against dontcare”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/23-00000122”, “dontcare”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [exten@sub-record-check:17] Return(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:19] GotoIf(“PJSIP/23-00000122”, “1?macrodial”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-exten-vm,s,25)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:25] GosubIf(“PJSIP/23-00000122”, “0?clrheader,1()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-exten-vm:26] Macro(“PJSIP/23-00000122”, “dial-one,HhTtr,13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:1] Set(“PJSIP/23-00000122”, “DEXTEN=13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:2] Set(“PJSIP/23-00000122”, “__CRM_SOURCE=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:3] ExecIf(“PJSIP/23-00000122”, “0?Set(__EXTTOCALL=13)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:4] Set(“PJSIP/23-00000122”, “DIALSTATUS_CW=”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“PJSIP/23-00000122”, “0?screen,1()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“PJSIP/23-00000122”, “0?cf,1()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:7] GotoIf(“PJSIP/23-00000122”, “1?skip1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-dial-one,s,10)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“PJSIP/23-00000122”, “0?nodial”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“PJSIP/23-00000122”, “0?continue”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:12] Set(“PJSIP/23-00000122”, “EXTHASCW=ENABLED”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:13] GotoIf(“PJSIP/23-00000122”, “0?next1:cwinusebusy”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-dial-one,s,25)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:25] GotoIf(“PJSIP/23-00000122”, “0?next3:continue”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-dial-one,s,27)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:27] GotoIf(“PJSIP/23-00000122”, “0?nodial”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:28] GosubIf(“PJSIP/23-00000122”, “1?dstring,1():dlocal,1()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:1] Set(“PJSIP/23-00000122”, “DSTRING=”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:2] Set(“PJSIP/23-00000122”, “DEVICES=13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/23-00000122”, “0?Return()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/23-00000122”, “0?Set(DEVICES=3)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:5] Set(“PJSIP/23-00000122”, “LOOPCNT=1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:6] Set(“PJSIP/23-00000122”, “ITER=1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:7] Set(“PJSIP/23-00000122”, “THISDIAL=PJSIP/13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“PJSIP/23-00000122”, “1?zap2dahdi,1()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“PJSIP/23-00000122”, “0?Return()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“PJSIP/23-00000122”, “NEWDIAL=”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“PJSIP/23-00000122”, “LOOPCNT2=1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“PJSIP/23-00000122”, “ITER2=1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“PJSIP/23-00000122”, “THISPART2=PJSIP/13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“PJSIP/23-00000122”, “0?Set(THISPART2=DAHDIIP/13)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“PJSIP/23-00000122”, “NEWDIAL=PJSIP/13&”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“PJSIP/23-00000122”, “ITER2=2”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“PJSIP/23-00000122”, “0?begin2”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“PJSIP/23-00000122”, “THISDIAL=PJSIP/13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“PJSIP/23-00000122”, “0?docheck”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:10] NoOp(“PJSIP/23-00000122”, “Debug: Found PJSIP Destination PJSIP/13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf(“PJSIP/23-00000122”, “0?doset”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:12] NoOp(“PJSIP/23-00000122”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:13] Set(“PJSIP/23-00000122”, “THISDIAL=PJSIP/13/sip:[email protected]:50609”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf(“PJSIP/23-00000122”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf(“PJSIP/23-00000122”, “0?skipset”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:16] Set(“PJSIP/23-00000122”, “DSTRING=PJSIP/13/sip:[email protected]:50609&”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:17] Set(“PJSIP/23-00000122”, “ITER=2”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf(“PJSIP/23-00000122”, “0?begin”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf(“PJSIP/23-00000122”, “0?Return()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:20] Set(“PJSIP/23-00000122”, “DSTRING=PJSIP/13/sip:13@EXTERNAL-IP:50609”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [dstring@macro-dial-one:21] Return(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:29] GotoIf(“PJSIP/23-00000122”, “0?nodial”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:30] GotoIf(“PJSIP/23-00000122”, “0?skiptrace”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:31] GosubIf(“PJSIP/23-00000122”, “1?ctset,1():ctclear,1()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [ctset@macro-dial-one:1] Set(“PJSIP/23-00000122”, “DB(CALLTRACE/13)=23”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [ctset@macro-dial-one:2] Return(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:32] Set(“PJSIP/23-00000122”, “D_OPTIONS=HhTtr”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:33] GosubIf(“PJSIP/23-00000122”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:34] NoOp(“PJSIP/23-00000122”, "Blind Transfer: , Attended Transfer: , User: 23, Alert Info: ") in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:35] ExecIf(“PJSIP/23-00000122”, “1?Set(ALERT_INFO=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“PJSIP/23-00000122”, “0?Set(ALERT_INFO=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“PJSIP/23-00000122”, “0?Set(ALERT_INFO=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“PJSIP/23-00000122”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“PJSIP/23-00000122”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:40] GosubIf(“PJSIP/23-00000122”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:41] ExecIf(“PJSIP/23-00000122”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:42] GosubIf(“PJSIP/23-00000122”, “0?qwait,1()”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:43] Set(“PJSIP/23-00000122”, “__CWIGNORE=”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:44] Set(“PJSIP/23-00000122”, “__KEEPCID=TRUE”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:45] GotoIf(“PJSIP/23-00000122”, “0?usegoto,1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:46] GotoIf(“PJSIP/23-00000122”, “0?godial”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:47] Gosub(“PJSIP/23-00000122”, “sub-presencestate-display,s,1(13)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@sub-presencestate-display:1] Goto(“PJSIP/23-00000122”, “state-not_set,1”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set(“PJSIP/23-00000122”, “PRESENCESTATE_DISPLAY=”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:48] Set(“PJSIP/23-00000122”, “CONNECTEDLINE(name,i)=Back Desk 2”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:49] Set(“PJSIP/23-00000122”, “CONNECTEDLINE(num)=13”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:50] Set(“PJSIP/23-00000122”, “D_OPTIONS=HhTtrI”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:51] Macro(“PJSIP/23-00000122”, “dialout-one-predial-hook,”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:52] ExecIf(“PJSIP/23-00000122”, “0?Set(D_OPTIONS=HhtrII)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:53] NoOp(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:54] ExecIf(“PJSIP/23-00000122”, “0?Set(D_OPTIONS=HhTtrIg)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-dial-one:55] Dial(“PJSIP/23-00000122”, “PJSIP/13/sip:13@EXTERNAL IP:50609,HhTtrIb(func-apply-sipheaders^s^1)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_stack.c: PJSIP/13-00000123 Internal Gosub(func-apply-sipheaders,s,1) start
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/13-00000123”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/13-00000123”, “Applying SIP Headers to channel PJSIP/13-00000123”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/13-00000123”, “TECH=PJSIP”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/13-00000123”, “SIPHEADERKEYS=”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/13-00000123”, “0”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_while.c: Jumping to priority 12
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/13-00000123”, “”) in new stack
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_stack.c: Spawn extension (from-internal, 13, 1) exited non-zero on ‘PJSIP/13-00000123’
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_stack.c: PJSIP/13-00000123 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_dial.c: Called PJSIP/13/sip:[email protected]:50609
[2019-07-24 10:02:32] VERBOSE[16536] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-07-24 10:02:32] VERBOSE[16536] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-07-24 10:02:32] VERBOSE[16536] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_dial.c: Connected line update to PJSIP/23-00000122 prevented.
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_dial.c: PJSIP/13-00000123 is ringing
[2019-07-24 10:02:32] VERBOSE[25983][C-00000104] app_dial.c: PJSIP/13-00000123 is ringing
[2019-07-24 10:02:35] VERBOSE[25983][C-00000104] app_dial.c: PJSIP/13-00000123 answered PJSIP/23-00000122
[2019-07-24 10:02:35] WARNING[25984][C-00000104] features_config.c: Unknown DYNAMIC_FEATURES item ‘apprecord’ on channel PJSIP/13-00000123.
[2019-07-24 10:02:35] VERBOSE[25984][C-00000104] bridge_channel.c: Channel PJSIP/13-00000123 joined ‘simple_bridge’ basic-bridge <8880848a-afa7-4890-9197-0b509017ba7d>
[2019-07-24 10:02:35] WARNING[25983][C-00000104] features_config.c: Unknown DYNAMIC_FEATURES item ‘apprecord’ on channel PJSIP/23-00000122.
[2019-07-24 10:02:35] VERBOSE[25983][C-00000104] bridge_channel.c: Channel PJSIP/23-00000122 joined ‘simple_bridge’ basic-bridge <8880848a-afa7-4890-9197-0b509017ba7d>
[2019-07-24 10:02:41] VERBOSE[25984][C-00000104] bridge_channel.c: Channel PJSIP/13-00000123 left ‘native_rtp’ basic-bridge <8880848a-afa7-4890-9197-0b509017ba7d>
[2019-07-24 10:02:41] VERBOSE[25983][C-00000104] bridge_channel.c: Channel PJSIP/23-00000122 left ‘native_rtp’ basic-bridge <8880848a-afa7-4890-9197-0b509017ba7d>
[2019-07-24 10:02:41] VERBOSE[25983][C-00000104] app_macro.c: Spawn extension (macro-dial-one, s, 55) exited non-zero on ‘PJSIP/23-00000122’ in macro ‘dial-one’
[2019-07-24 10:02:41] VERBOSE[25983][C-00000104] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on ‘PJSIP/23-00000122’ in macro ‘exten-vm’
[2019-07-24 10:02:41] VERBOSE[25983][C-00000104] pbx.c: Spawn extension (ext-local, 13, 2) exited non-zero on ‘PJSIP/23-00000122’
[2019-07-24 10:02:41] VERBOSE[25983][C-00000104] pbx.c: Executing [h@ext-local:1] Macro(“PJSIP/23-00000122”, “hangupcall,”) in new stack
[2019-07-24 10:02:41] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/23-00000122”, “1?theend”) in new stack
[2019-07-24 10:02:41] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-07-24 10:02:42] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/23-00000122”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-07-24 10:02:42] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/23-00000122”, "PJSIP/13-00000123 montior file= ") in new stack
[2019-07-24 10:02:42] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“PJSIP/23-00000122”, “1?skipagi”) in new stack
[2019-07-24 10:02:42] VERBOSE[25983][C-00000104] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2019-07-24 10:02:42] VERBOSE[25983][C-00000104] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“PJSIP/23-00000122”, “”) in new stack
[2019-07-24 10:02:42] VERBOSE[25983][C-00000104] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘PJSIP/23-00000122’ in macro ‘hangupcall’
[2019-07-24 10:02:42] VERBOSE[25983][C-00000104] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘PJSIP/23-00000122’

[cyberdocwi]

Hello Stewart1,

We are using PJSIP.

Calls are coming in and out. Ringing is working fine. No audio.

I do not believe the phones are performing NAT traversal. When they register to FreePBX, they are using the external IP address, not any internal ones.

My phones setup worked fine in the test lab with a different firewall, so I am suspecting the SonicWall.

Thanks,

Christian