No sound and call drops after 5-6 seconds

FreePBX Providers
1

When call is placed, the destination extensions ring (in the same group or using sip trunk to another PBX box).
There is no sound either way. The call drops after 5-6 seconds.
The devices are capable of producing/capturing sound and worked fine in another environment.
I suspect it is a NAT issue.
The PBX is running in the virtual box under FreeBSD/BHyve
Firewall - Barracuda appliance, pfSense, with Surricata intrusion prevention.
Port forwarding - UDP 20000-24000.
Where do I start looking for the solution?
Here is the full log:

[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Result of 'ONETOUCH_RECFILE' is NULL
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Evaluating 'CDR(recordingfile)' (from 'CDR(recordingfile)}"=""' len 18)
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Function CDR(recordingfile) result is ''
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Expression result is '0'
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Evaluating 'ONETOUCH_RECFILE' (from 'ONETOUCH_RECFILE})' len 16)
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Result of 'ONETOUCH_RECFILE' is NULL
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Function CDR(dstchannel) result is 'SIP/101-0000001a'
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Result of 'MIXMONITOR_FILENAME' is NULL
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx.c: Launching 'NoOp'
[2019-12-11 19:45:01] VERBOSE[7186][C-0000000e] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/106-00000019", "SIP/101-0000001a montior file= ") in new stack
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] app_macro.c: Executed application: Noop
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Releasing ODBC handle 0x7ff8a00186e8 into pool
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Reusing ODBC handle 0x7ff8a00186e8 from class 'asteriskcdrdb'
[2019-12-11 19:45:01] DEBUG[10875] cel_odbc.c: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('HANGUP',{ts '2019-12-11 19:45:01.014885'},'Front Desk 1','101','101','','','','from-internal','SIP/101-0000001a','AppDial','(Outgoing Line)',3,'','1576111486.26','1576111486.25','','','{"hangupcause":18,"hangupsource":"","dialstatus":""}')]
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Function CDR(dstchannel) result is 'SIP/101-0000001a'
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Result of 'MIXMONITOR_FILENAME' is NULL
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx_variables.c: Expression result is '1'
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx.c: Launching 'GotoIf'
[2019-12-11 19:45:01] VERBOSE[7186][C-0000000e] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/106-00000019", "1?skipagi") in new stack
[2019-12-11 19:45:01] VERBOSE[7186][C-0000000e] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] app_macro.c: Executed application: GotoIf
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx.c: Launching 'Hangup'
[2019-12-11 19:45:01] VERBOSE[7186][C-0000000e] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/106-00000019", "") in new stack
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] channel.c: Soft-Hanging (0x20) up channel 'SIP/106-00000019'
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] app_macro.c: Spawn extension (macro-hangupcall,s,7) exited non-zero on 'SIP/106-00000019' in macro 'hangupcall'
[2019-12-11 19:45:01] VERBOSE[7186][C-0000000e] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/106-00000019' in macro 'hangupcall'
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] pbx.c: Spawn extension (ext-local,h,1) exited non-zero on 'SIP/106-00000019'
[2019-12-11 19:45:01] VERBOSE[7186][C-0000000e] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/106-00000019'
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] channel.c: Channel 0x7ff8880b3378 'SIP/106-00000019' hanging up. Refs: 2
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] chan_sip.c: Hangup call SIP/106-00000019, SIP callid fiwNvhcFzVMpRsFyohAedw..
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] chan_sip.c: Updating call counter for incoming call
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] netsock2.c: Splitting '10.0.42.6:35650' into...
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] netsock2.c: ...host '10.0.42.6' and port '35650'.
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] chan_sip.c: Trying to put 'BYE sip:106' onto UDP socket destined for 10.0.42.6:35650
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] channel.c: Channel 0x7ff8880b3378 'SIP/106-00000019' destroying
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] stasis.c: Topic 'cache:48/channel:1576111486.25': 0x7ff8880b8c48 destroyed
[2019-12-11 19:45:01] DEBUG[7186][C-0000000e] stasis.c: Topic 'channel:1576111486.25': 0x7ff8880c6cf8 destroyed
[2019-12-11 19:45:01] DEBUG[10813] devicestate.c: No provider found, checking channel drivers for SIP - 106
[2019-12-11 19:45:01] DEBUG[10813] chan_sip.c: Checking device state for peer 106
[2019-12-11 19:45:01] DEBUG[10813] devicestate.c: Changing state for SIP/106 - state 1 (Not in use)
[2019-12-11 19:45:01] DEBUG[10813] devicestate.c: No provider found, checking channel drivers for SIP - 106
[2019-12-11 19:45:01] DEBUG[10813] chan_sip.c: Checking device state for peer 106
[2019-12-11 19:45:01] DEBUG[10813] devicestate.c: Changing state for SIP/106 - state 1 (Not in use)
[2019-12-11 19:45:01] DEBUG[10956] app_queue.c: Device 'SIP/106' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2019-12-11 19:45:01] DEBUG[10873] devicestate.c: Checking if I can find provider for "Custom" - number: DND106
[2019-12-11 19:45:01] DEBUG[10873] devicestate.c: Checking provider SLA with Custom
[2019-12-11 19:45:01] DEBUG[10873] devicestate.c: Checking provider Meetme with Custom
[2019-12-11 19:45:01] DEBUG[10873] devicestate.c: Checking provider Custom with Custom
[2019-12-11 19:45:01] DEBUG[10873] db.c: Unable to find key 'DND106' in family 'CustomDevstate'
[2019-12-11 19:45:01] DEBUG[10872] res_odbc.c: Reusing ODBC handle 0x7ff88c04fec8 from class 'asteriskcdrdb'
[2019-12-11 19:45:01] DEBUG[10872] cdr_adaptive_odbc.c: Executing [INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid,cnum,cnam,linkedid,sequence) VALUES ({ ts '2019-12-11 19:44:46' },'"Dr O office" <106>','106','101','ext-local','SIP/106-00000019','SIP/101-0000001a','Dial','SIP/101,15,HhTtrIb(func-apply-sipheaders^s^1)',14,6,'ANSWERED',3,'1576111486.25','106','Dr O office','1576111486.25',25)]
[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: ExtensionStatus
Privilege: call,all
Exten: 106
Context: ext-local
Hint: SIP/106&Custom:DND106,CustomPresence:106
Status: 0
StatusText: Idle


[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Releasing ODBC handle 0x7ff8a00186e8 into pool
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Reusing ODBC handle 0x7ff8a00186e8 from class 'asteriskcdrdb'
[2019-12-11 19:45:01] DEBUG[10875] cel_odbc.c: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('CHAN_END',{ts '2019-12-11 19:45:01.019181'},'Front Desk 1','101','101','','','','from-internal','SIP/101-0000001a','AppDial','(Outgoing Line)',3,'','1576111486.26','1576111486.25','','','')]
[2019-12-11 19:45:01] DEBUG[10872] res_odbc.c: Releasing ODBC handle 0x7ff88c04fec8 into pool
[2019-12-11 19:45:01] DEBUG[10800] threadpool.c: Increasing threadpool stasis/pool's size by 1
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Releasing ODBC handle 0x7ff8a00186e8 into pool
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Reusing ODBC handle 0x7ff8a00186e8 from class 'asteriskcdrdb'
[2019-12-11 19:45:01] DEBUG[10875] cel_odbc.c: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('HANGUP',{ts '2019-12-11 19:45:01.020928'},'Dr O office','106','106','','101','h','ext-local','SIP/106-00000019','','',3,'','1576111486.25','1576111486.25','','','{"hangupcause":18,"hangupsource":"dialplan/builtin","dialstatus":"ANSWER"}')]
[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 3
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Extension: s
Application: ExecIf
AppData: 0?Set(CDR(recordingfile)=)


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 3
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: MACRO_DEPTH
Value: 1


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 4
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Extension: s
Application: NoOp
AppData: SIP/101-0000001a montior file=


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 4
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: MACRO_DEPTH
Value: 1


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 5
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Extension: s
Application: GotoIf
AppData: 1?skipagi


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 5
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: MACRO_DEPTH
Value: 1


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Extension: s
Application: Hangup
AppData:


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: MACRO_DEPTH
Value: 0


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: MACRO_EXTEN
Value:


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: MACRO_CONTEXT
Value:


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: MACRO_PRIORITY
Value:


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: RTPAUDIOQOS
Value: ssrc=1543810056;themssrc=0;lp=0;rxjitter=0.000000;rxcount=0;txjitter=0.000000;txcount=0;rlp=0;rtt=0.000000


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: RTPAUDIOQOSJITTER
Value: minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000;


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: RTPAUDIOQOSLOSS
Value: minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000;


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: macro-hangupcall
Exten: s
Priority: 7
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: RTPAUDIOQOSRTT
Value: minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: ext-local
Exten: h
Priority: 1
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Variable: RTPAUDIOQOS
Value: ssrc=1543810056;themssrc=0;lp=0;rxjitter=0.000000;rxcount=0;txjitter=0.000000;txcount=0;rlp=0;rtt=0.000000


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: Hangup
Privilege: call,all
Channel: SIP/106-00000019
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 106
CallerIDName: Dr O office
ConnectedLineNum: 101
ConnectedLineName: Front Desk 1
Language: en
AccountCode:
Context: ext-local
Exten: h
Priority: 1
Uniqueid: 1576111486.25
Linkedid: 1576111486.25
Cause: 18
Cause-txt: No user responding


[2019-12-11 19:45:01] DEBUG[5696] manager.c: Examining AMI event:
Event: DeviceStateChange
Privilege: call,all
Device: SIP/106
State: NOT_INUSE


[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Releasing ODBC handle 0x7ff8a00186e8 into pool
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Reusing ODBC handle 0x7ff8a00186e8 from class 'asteriskcdrdb'
[2019-12-11 19:45:01] DEBUG[10875] cel_odbc.c: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('CHAN_END',{ts '2019-12-11 19:45:01.026426'},'Dr O office','106','106','','101','h','ext-local','SIP/106-00000019','','',3,'','1576111486.25','1576111486.25','','','')]
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Releasing ODBC handle 0x7ff8a00186e8 into pool
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Reusing ODBC handle 0x7ff8a00186e8 from class 'asteriskcdrdb'
[2019-12-11 19:45:01] DEBUG[10875] cel_odbc.c: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('LINKEDID_END',{ts '2019-12-11 19:45:01.027889'},'Dr O office','106','106','','101','h','ext-local','SIP/106-00000019','','',3,'','1576111486.25','1576111486.25','','','')]
[2019-12-11 19:45:01] DEBUG[10875] res_odbc.c: Releasing ODBC handle 0x7ff8a00186e8 into pool
[2019-12-11 19:45:01] DEBUG[10930] chan_sip.c: = Looking for Call ID: fiwNvhcFzVMpRsFyohAedw.. (Checking To) --From tag as2ca0d81a --To-tag dfe98e5a
[2019-12-11 19:45:01] DEBUG[10930][C-0000000e] chan_sip.c: Stopping retransmission on 'fiwNvhcFzVMpRsFyohAedw..' of Request 102: Match Found
[2019-12-11 19:45:01] DEBUG[10930] chan_sip.c: Destroying SIP dialog fiwNvhcFzVMpRsFyohAedw..
[2019-12-11 19:45:01] DEBUG[10930] rtp_engine.c: Destroyed RTP instance '0x7ff88800cdc8'
[2019-12-11 19:45:02] DEBUG[7228] manager.c: Running action 'Login'
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: = Looking for Call ID: DJP7itWC6ldgxmCL0pVRMg.. (Checking From) --From tag badd674e --To-tag
[2019-12-11 19:45:02] DEBUG[10930] acl.c: For destination '10.0.42.6', our source address is '192.168.42.8'.
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Target address 10.0.42.6:35650 is not local, substituting externaddr
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Setting AST_TRANSPORT_UDP with address 70.164.47.88:5060
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: Splitting '10.0.42.6:35650' into...
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: ...host '10.0.42.6' and port '35650'.
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Allocating new SIP dialog for DJP7itWC6ldgxmCL0pVRMg.. - REGISTER (No RTP)
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: Splitting '10.0.42.6:35650' into...
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: ...host '10.0.42.6' and port '35650'.
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: Splitting '192.168.42.8' into...
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: ...host '192.168.42.8' and port ''.
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.0.42.6:35650
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: = Looking for Call ID: DJP7itWC6ldgxmCL0pVRMg.. (Checking From) --From tag badd674e --To-tag
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: Splitting '10.0.42.6:35650' into...
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: ...host '10.0.42.6' and port '35650'.
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: Splitting '192.168.42.8' into...
[2019-12-11 19:45:02] DEBUG[10930] netsock2.c: ...host '192.168.42.8' and port ''.
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Store REGISTER's src-IP:port for call routing.
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: build_path: do not use Path headers
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Allocating new SIP dialog for 3f8cdd8b2a14c8591c8ef4541d94a6d7@[::1]:5060 - OPTIONS (No RTP)
[2019-12-11 19:45:02] DEBUG[10930] acl.c: For destination '10.0.42.6', our source address is '192.168.42.8'.
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Target address 10.0.42.6:35650 is not local, substituting externaddr
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Setting AST_TRANSPORT_UDP with address 70.164.47.88:5060
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: SIP call-id changed from '3f8cdd8b2a14c8591c8ef4541d94a6d7@[::1]:5060' to '[email protected]:5060'
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Initializing initreq for method OPTIONS - callid [email protected]:5060
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 10.0.42.6:35650
[2019-12-11 19:45:02] DEBUG[10930] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.0.42.6:35650
[2019-12-11 19:45:02] DEBUG[10813] devicestate.c: No provider found, checking channel drivers for SIP - 106
[2019-12-11 19:45:02] DEBUG[10813] chan_sip.c: Checking device state for peer 106
[2019-12-11 19:45:02] DEBUG[10813] devicestate.c: Changing state for SIP/106 - state 1 (Not in use)
[2019-12-11 19:45:02] DEBUG[5696] manager.c: Examining AMI event:
Event: PeerStatus
Privilege: system,all
ChannelType: SIP
Peer: SIP/106
PeerStatus: Registered
Address: 10.0.42.6:35650


[2019-12-11 19:45:03] DEBUG[10930] chan_sip.c: = Looking for Call ID: [email protected]:5060 (Checking To) --From tag as7e8ecaa5 --To-tag 02b41214
[2019-12-11 19:45:03] DEBUG[10930] chan_sip.c: Stopping retransmission on '[email protected]:5060' of Request 102: Match Found
[2019-12-11 19:45:03] DEBUG[10930] chan_sip.c: Destroying SIP dialog [email protected]:5060
[2019-12-11 19:45:03] DEBUG[7241] manager.c: Running action 'Login'
[2019-12-11 19:45:05] DEBUG[10913] res_pjsip_registrar.c: Woke up at 1576111505 Interval: 30
[2019-12-11 19:45:05] DEBUG[10913] res_pjsip_registrar.c: Expiring 0 contacts
[2019-12-11 19:45:08] DEBUG[10930] chan_sip.c: = Looking for Call ID: [email protected] (Checking From) --From tag 95aa63e81927eb29o0 --To-tag
[2019-12-11 19:45:08] DEBUG[10930] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
[2019-12-11 19:45:08] DEBUG[10930] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.42.131:5060
[2019-12-11 19:45:14] DEBUG[7250] manager.c: Running action 'Login'
2

Hi
Indeed, your issue seems to come from NAT.
You must to check your network environment and network & SIP settings in FreePBX as well.
Be sure to declare all subnet in NAT if you’ve got more than one network.
eg:

10.0.42.0/24
10.0.45.0/24
...etc
Just an idea like that.
3

Also, make sure that your virtual machine is using bridged networking


and the pbx has its private address in the same subnet as the freeBSD machine and the phones.

For calls between local extensions, the Barracuda / pfSense should not be involved at all.

4

Being a newbie to this all, I overlooked that there are several places for IP settings to declare.
The system was not installed from scratch to begin with so it retained some old settings.
It works now. Thank you

5

Ok great.
You’re welcome :slight_smile:

6

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