No audio on internal or external calls

Hello,

I’m new to Asterisk/FreePBX… I setup a server running FreePBX 14 and Asterisk 13.

I setup the SIP trunks properly (I believe) and created two test extensions. I have connected to the two test extensions via X-Lite on two different computers. The extensions can call each other no problem, but when they connect there’s no audio at all from either end.

I can also successfully make an external call and receive a call from external parties, but again no audio at all.

I have tried to disable the firewall in FreePBX, but that does not help - and I have tried to increase the port range for RTP which does not help. I have also ensured that the “Local Networks” under “NAT Settings” is set appropriate with our private address scheme.

Any suggestions for how I can get this working properly?

Thanks in advance!

https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs

If your phones and freepbx servers are all on your local lan, most probably you have a codec mismatch.

If freepbx is hosted on the cloud, most probably you have a NAT issue.

In any case, you should provide more info about your specific setup so we can provide useful help.

Here’s the output from the log as requested (produced by calling from one local extension to another local extension). X-Lite shows that the microphone is picking up sound, but it never comes through on the other end.

[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/233-0000002a”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/233-0000002a”, "PJSIP/234-0000002b monior file= ") in new stack
[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] pbx.c: Executing [s@macro-hangupcall:5] AGI(“PJSIP/233-0000002a”, “attendedtransfer-rec-restart.php,PJSIP/234-0000002b,”) in new stack
[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] res_agi.c: <PJSIP/233-0000002a>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“PJSIP/233-0000002a”, “”) in new stack
[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/233-0000002a’ in macro ‘hangupcall’
[2018-09-17 12:57:32] VERBOSE[56453][C-00000018] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘PJSIP/233-0000002a’
[2018-09-17 12:58:27] VERBOSE[52754] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘10.42.0.23’
[2018-09-17 12:58:27] VERBOSE[52754] netsock2.c: Using SIP RTP Audio TOS bits 184
[2018-09-17 12:58:27] VERBOSE[52754] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2018-09-17 12:58:27] VERBOSE[52754] netsock2.c: Using SIP RTP Audio CoS mark 5
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [234@from-internal:1] GotoIf(“PJSIP/233-0000002c”, “1?ext-local,234,1:followme-check,234,1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (ext-local,234,1)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [234@ext-local:1] Set(“PJSIP/233-0000002c”, “__RINGTIMER=15”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [234@ext-local:2] Macro(“PJSIP/233-0000002c”, “exten-vm,novm,234,0,0,0”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:1] Macro(“PJSIP/233-0000002c”, “user-callerid,”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/233-0000002c”, “TOUCH_MONITOR=1537203507.44”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/233-0000002c”, “AMPUSER=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/233-0000002c”, “0?report”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/233-0000002c”, “1?Set(REALCALLERIDNUM=233)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/233-0000002c”, “AMPUSER=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/233-0000002c”, “0?limit”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/233-0000002c”, “AMPUSERCIDNAME=User 1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/233-0000002c”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/233-0000002c”, “0?report”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/233-0000002c”, “AMPUSERCID=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/233-0000002c”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/233-0000002c”, “CALLERID(all)=“User 1” <233>”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/233-0000002c”, “0?limit”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/233-0000002c”, “0?Set(GROUP(concurrency_limit)=233)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/233-0000002c”, “0?Set(CHANNEL(language)=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/233-0000002c”, “Macro Depth is 2”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/233-0000002c”, “1?report2:macroerror”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/233-0000002c”, “0?continue”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“PJSIP/233-0000002c”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:20] Set(“PJSIP/233-0000002c”, “__TTL=64”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“PJSIP/233-0000002c”, “1?continue”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/233-0000002c”, “CALLERID(number)=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/233-0000002c”, “CALLERID(name)=User 1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/233-0000002c”, “0?cnum”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/233-0000002c”, “CDR(cnam)=User 1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/233-0000002c”, “CDR(cnum)=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/233-0000002c”, “CHANNEL(language)=en”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:2] Set(“PJSIP/233-0000002c”, “RingGroupMethod=none”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:3] Set(“PJSIP/233-0000002c”, “__EXTTOCALL=234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:4] Set(“PJSIP/233-0000002c”, “__PICKUPMARK=234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:5] Set(“PJSIP/233-0000002c”, “RT=”) in new stack
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(“PJSIP/233-0000002c”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(“PJSIP/233-0000002c”, “0?MacroExit()”) in new stack
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:8] ExecIf(“PJSIP/233-0000002c”, “0?Gosub(ext-intercom,*80234,1())”) in new stack
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:9] ExecIf(“PJSIP/233-0000002c”, “0?MacroExit()”) in new stack
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:10] ExecIf(“PJSIP/233-0000002c”, “0?ChanSpy(PJSIP/234,q)”) in new stack
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:11] ExecIf(“PJSIP/233-0000002c”, “0?MacroExit()”) in new stack
[2018-09-17 12:58:27] WARNING[56727][C-00000019] chan_sip.c: This function can only be used on SIP channels.
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:12] ExecIf(“PJSIP/233-0000002c”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:13] ExecIf(“PJSIP/233-0000002c”, “0?MacroExit()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:14] ExecIf(“PJSIP/233-0000002c”, “0?Gosub(ext-intercom,*80234,1())”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:15] ExecIf(“PJSIP/233-0000002c”, “0?MacroExit()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:16] ExecIf(“PJSIP/233-0000002c”, “0?ChanSpy(PJSIP/234,q)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:17] ExecIf(“PJSIP/233-0000002c”, “0?MacroExit()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:18] Gosub(“PJSIP/233-0000002c”, “sub-record-check,s,1(exten,234,dontcare)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/233-0000002c”, “0?initialized”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/233-0000002c”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/233-0000002c”, “NOW=1537203507”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/233-0000002c”, “__DAY=17”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/233-0000002c”, “__MONTH=09”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/233-0000002c”, “__YEAR=2018”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/233-0000002c”, “__TIMESTR=20180917-125827”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/233-0000002c”, “__FROMEXTEN=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/233-0000002c”, “__MON_FMT=wav”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/233-0000002c”, “Recordings initialized”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/233-0000002c”, “0?Set(ARG3=dontcare)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/233-0000002c”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/233-0000002c”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/233-0000002c”, “5?checkaction”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/233-0000002c”, “1?sub-record-check,exten,1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:1] NoOp(“PJSIP/233-0000002c”, “Exten Recording Check between 233 and 234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:2] Set(“PJSIP/233-0000002c”, “CALLTYPE=internal”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“PJSIP/233-0000002c”, “0?Set(CALLTYPE=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:4] Set(“PJSIP/233-0000002c”, “CALLEE=dontcare”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“PJSIP/233-0000002c”, “0?Set(CALLEE=dontcare)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“PJSIP/233-0000002c”, “0?callee”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:7] GotoIf(“PJSIP/233-0000002c”, “1?caller”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:13] Set(“PJSIP/233-0000002c”, “RECMODE=dontcare”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:14] ExecIf(“PJSIP/233-0000002c”, “0?Set(RECMODE=dontcare)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:15] ExecIf(“PJSIP/233-0000002c”, “1?Set(RECMODE=dontcare)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:16] Gosub(“PJSIP/233-0000002c”, “recordcheck,1(dontcare,internal,234)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/233-0000002c”, “Starting recording check against dontcare”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/233-0000002c”, “dontcare”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [exten@sub-record-check:17] Return(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:19] GotoIf(“PJSIP/233-0000002c”, “1?macrodial”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (macro-exten-vm,s,25)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:25] GosubIf(“PJSIP/233-0000002c”, “0?clrheader,1()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-exten-vm:26] Macro(“PJSIP/233-0000002c”, “dial-one,HhTtr,234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:1] Set(“PJSIP/233-0000002c”, “DEXTEN=234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:2] Set(“PJSIP/233-0000002c”, “__CRM_SOURCE=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:3] ExecIf(“PJSIP/233-0000002c”, “0?Set(__EXTTOCALL=234)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:4] Set(“PJSIP/233-0000002c”, “DIALSTATUS_CW=”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“PJSIP/233-0000002c”, “0?screen,1()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“PJSIP/233-0000002c”, “0?cf,1()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:7] GotoIf(“PJSIP/233-0000002c”, “1?skip1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (macro-dial-one,s,10)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“PJSIP/233-0000002c”, “0?nodial”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“PJSIP/233-0000002c”, “0?continue”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:12] Set(“PJSIP/233-0000002c”, “EXTHASCW=ENABLED”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:13] GotoIf(“PJSIP/233-0000002c”, “0?next1:cwinusebusy”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (macro-dial-one,s,25)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:25] GotoIf(“PJSIP/233-0000002c”, “0?next3:continue”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (macro-dial-one,s,27)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:27] GotoIf(“PJSIP/233-0000002c”, “0?nodial”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:28] GosubIf(“PJSIP/233-0000002c”, “1?dstring,1():dlocal,1()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:1] Set(“PJSIP/233-0000002c”, “DSTRING=”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:2] Set(“PJSIP/233-0000002c”, “DEVICES=234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/233-0000002c”, “0?Return()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/233-0000002c”, “0?Set(DEVICES=34)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:5] Set(“PJSIP/233-0000002c”, “LOOPCNT=1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:6] Set(“PJSIP/233-0000002c”, “ITER=1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:7] Set(“PJSIP/233-0000002c”, “THISDIAL=PJSIP/234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“PJSIP/233-0000002c”, “1?zap2dahdi,1()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“PJSIP/233-0000002c”, “0?Return()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“PJSIP/233-0000002c”, “NEWDIAL=”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“PJSIP/233-0000002c”, “LOOPCNT2=1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“PJSIP/233-0000002c”, “ITER2=1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“PJSIP/233-0000002c”, “THISPART2=PJSIP/234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“PJSIP/233-0000002c”, “0?Set(THISPART2=DAHDIIP/234)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“PJSIP/233-0000002c”, “NEWDIAL=PJSIP/234&”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“PJSIP/233-0000002c”, “ITER2=2”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“PJSIP/233-0000002c”, “0?begin2”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“PJSIP/233-0000002c”, “THISDIAL=PJSIP/234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“PJSIP/233-0000002c”, “0?docheck”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:10] NoOp(“PJSIP/233-0000002c”, “Debug: Found PJSIP Destination PJSIP/234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf(“PJSIP/233-0000002c”, “0?doset”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:12] NoOp(“PJSIP/233-0000002c”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:13] Set(“PJSIP/233-0000002c”, “THISDIAL=PJSIP/234/sip:[email protected]:49262;rinstance=812c27609c7cbfae”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf(“PJSIP/233-0000002c”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf(“PJSIP/233-0000002c”, “0?skipset”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:16] Set(“PJSIP/233-0000002c”, “DSTRING=PJSIP/234/sip:[email protected]:49262;rinstance=812c27609c7cbfae&”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:17] Set(“PJSIP/233-0000002c”, “ITER=2”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf(“PJSIP/233-0000002c”, “0?begin”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf(“PJSIP/233-0000002c”, “0?Return()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:20] Set(“PJSIP/233-0000002c”, “DSTRING=PJSIP/234/sip:[email protected]:49262;rinstance=812c27609c7cbfae”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [dstring@macro-dial-one:21] Return(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:29] GotoIf(“PJSIP/233-0000002c”, “0?nodial”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:30] GotoIf(“PJSIP/233-0000002c”, “0?skiptrace”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:31] GosubIf(“PJSIP/233-0000002c”, “1?ctset,1():ctclear,1()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [ctset@macro-dial-one:1] Set(“PJSIP/233-0000002c”, “DB(CALLTRACE/234)=233”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [ctset@macro-dial-one:2] Return(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:32] Set(“PJSIP/233-0000002c”, “D_OPTIONS=HhTtr”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:33] GosubIf(“PJSIP/233-0000002c”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:34] NoOp(“PJSIP/233-0000002c”, "Blind Transfer: , Attended Transfer: , User: 233, Alert Info: ") in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:35] ExecIf(“PJSIP/233-0000002c”, “1?Set(ALERT_INFO=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“PJSIP/233-0000002c”, “0?Set(ALERT_INFO=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“PJSIP/233-0000002c”, “0?Set(ALERT_INFO=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“PJSIP/233-0000002c”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“PJSIP/233-0000002c”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:40] GosubIf(“PJSIP/233-0000002c”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:41] ExecIf(“PJSIP/233-0000002c”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:42] GosubIf(“PJSIP/233-0000002c”, “0?qwait,1()”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:43] Set(“PJSIP/233-0000002c”, “__CWIGNORE=”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:44] Set(“PJSIP/233-0000002c”, “__KEEPCID=TRUE”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:45] GotoIf(“PJSIP/233-0000002c”, “0?usegoto,1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:46] GotoIf(“PJSIP/233-0000002c”, “0?godial”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:47] Gosub(“PJSIP/233-0000002c”, “sub-presencestate-display,s,1(234)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@sub-presencestate-display:1] Goto(“PJSIP/233-0000002c”, “state-not_set,1”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set(“PJSIP/233-0000002c”, “PRESENCESTATE_DISPLAY=”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:48] Set(“PJSIP/233-0000002c”, “CONNECTEDLINE(name,i)=User 2”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:49] Set(“PJSIP/233-0000002c”, “CONNECTEDLINE(num)=234”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:50] Set(“PJSIP/233-0000002c”, “D_OPTIONS=HhTtrI”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:51] Macro(“PJSIP/233-0000002c”, “dialout-one-predial-hook,”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:52] ExecIf(“PJSIP/233-0000002c”, “0?Set(D_OPTIONS=HhtrII)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:53] NoOp(“PJSIP/233-0000002c”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:54] ExecIf(“PJSIP/233-0000002c”, “0?Set(D_OPTIONS=HhTtrIg)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@macro-dial-one:55] Dial(“PJSIP/233-0000002c”, “PJSIP/234/sip:[email protected]:49262;rinstance=812c27609c7cbfae,HhTtrIb(func-apply-sipheaders^s^1)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] app_stack.c: PJSIP/234-0000002d Internal Gosub(func-apply-sipheaders,s,1) start
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/234-0000002d”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/234-0000002d”, “Applying SIP Headers to channel”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/234-0000002d”, “SIPHEADERKEYS=”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf(“PJSIP/234-0000002d”, “0?Set(Rheader=1)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/234-0000002d”, “0”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] app_while.c: Jumping to priority 9
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/234-0000002d”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/234-0000002d”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] pbx.c: Executing [s@func-apply-sipheaders:12] Return(“PJSIP/234-0000002d”, “”) in new stack
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] app_stack.c: Spawn extension (from-internal, 234, 1) exited non-zero on ‘PJSIP/234-0000002d’
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] app_stack.c: PJSIP/234-0000002d Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] app_dial.c: Called PJSIP/234/sip:[email protected]:49262;rinstance=812c27609c7cbfae
[2018-09-17 12:58:27] VERBOSE[52754] netsock2.c: Using SIP RTP Audio TOS bits 184
[2018-09-17 12:58:27] VERBOSE[52754] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2018-09-17 12:58:27] VERBOSE[52754] netsock2.c: Using SIP RTP Audio CoS mark 5
[2018-09-17 12:58:27] VERBOSE[56727][C-00000019] app_dial.c: Connected line update to PJSIP/233-0000002c prevented.
[2018-09-17 12:58:28] VERBOSE[56727][C-00000019] app_dial.c: PJSIP/234-0000002d is ringing
[2018-09-17 12:58:28] VERBOSE[56727][C-00000019] app_dial.c: PJSIP/234-0000002d is ringing
[2018-09-17 12:58:31] VERBOSE[56727][C-00000019] app_dial.c: PJSIP/234-0000002d answered PJSIP/233-0000002c
[2018-09-17 12:58:31] VERBOSE[56751][C-00000019] bridge_channel.c: Channel PJSIP/234-0000002d joined ‘simple_bridge’ basic-bridge <38c2c912-90f2-4cb9-be0d-a91f5b8f99b9>
[2018-09-17 12:58:31] VERBOSE[56727][C-00000019] bridge_channel.c: Channel PJSIP/233-0000002c joined ‘simple_bridge’ basic-bridge <38c2c912-90f2-4cb9-be0d-a91f5b8f99b9>

Yes, the phones and server are all of the local LAN. Initially I just had ulaw enabled in FreePBX and on the X-Lite installations. I then tried enabling all codecs in X-Lite and the corresponding codecs in FreePBX, but still no luck.

Please let me know whatever other info I can provide to help out.

If they are all local to the same network, you should not be having one-way sound issues, at least not related to NAT.
What is the output of command core show channels while the call is in progress?

Here is the output of that command during a local to local call:

Channel Location State Application(Data)
PJSIP/233-00000032 s@macro-dial-one:55 Up Dial(PJSIP/234/sip:[email protected]
PJSIP/234-00000033 (None) Up AppDial((Outgoing Line))
2 active channels
1 active call
29 calls processed

I seem to have got it working… looks like it was a mix of incorrect firewall settings within the FreePBX firewall.

One thing I still don’t understand though - I tried to connect an X-Lite softphone to the server and the extension kept not registering. The firewall had 10.42.10.0/24 added to the trusted zone and the workstation had the IP of 10.42.10.59, so it should have matched and been allowed through the firewall, correct?

In actuality, I had to manually add 10.42.10.59/32 to the firewall as trusted and then X-Lite finally registered. Is there a known bug with the firewall and CIDR?

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