Incorrect invite IP on SIP call over VPN
My test system is using Jami in Ubuntu accessing a FreePBX over L2TP/IPSec VPN. Registering extension works flawlessly, but when a call is started, invites are sent with the IP VPN box instead of client, so results in missed call.
I've tested same network and VoIP box with Linphone resulting in good call, so I think there is something not working in Jami.
This is log from /usr/lib/ring/dring -d -c
:
user@userPortatil:~$ /usr/lib/ring/dring -d -c
Jami Daemon 9.1.0, by Savoir-faire Linux 2004-2019
https://jami.net/
[Video support enabled]
[1584433298.376|21247|ringbufferpool.cpp:118 ] Ringbuffer created with id 'audiolayer_id'
[1584433298.491|21247|video_device.h :129 ] Default video settings: 960x540, 30 FPS
[1584433298.492|21247|manager.cpp :676 ] Not initialized
[1584433298.492|21247|manager.cpp :676 ] Not initialized
09:21:38.492 os_core_unix.c !pjlib 2.8-svn for POSIX initialized
[1584433298.492|21247|manager.cpp :738 ] Using PJSIP version 2.8-svn for x86_64-pc-linux-gnu
[1584433298.492|21247|manager.cpp :739 ] Using GnuTLS version 3.6.12
[1584433298.492|21247|manager.cpp :740 ] Using OpenDHT version 2.0.0
[1584433298.493|21247|manager.cpp :751 ] Configuration file path: /home/user/.config/jami/dring.yml
[1584433298.496|21247|accel.cpp :139 ] -- Starting encoding test for cuda with default device.
[AVHWDeviceContext @ 0x55bec3060b00] Cannot load libcuda.so.1
[AVHWDeviceContext @ 0x55bec3060b00] Could not dynamically load CUDA
[1584433298.497|21247|accel.cpp :102 ] Failed to create cuda device: -1313558101.
[1584433298.497|21247|accel.cpp :149 ] -- Init failed for cuda with default device.
[1584433298.497|21247|accel.cpp :153 ] -- Init encoding for cuda with device 0.
[AVHWDeviceContext @ 0x55bec3061040] Cannot load libcuda.so.1
[AVHWDeviceContext @ 0x55bec3061040] Could not dynamically load CUDA
[1584433298.497|21247|accel.cpp :102 ] Failed to create cuda device: -1313558101.
[1584433298.497|21247|accel.cpp :163 ] -- Init failed for cuda with device 0.
[1584433298.497|21247|accel.cpp :153 ] -- Init encoding for cuda with device 1.
[AVHWDeviceContext @ 0x55bec3061580] Cannot load libcuda.so.1
[AVHWDeviceContext @ 0x55bec3061580] Could not dynamically load CUDA
[1584433298.497|21247|accel.cpp :102 ] Failed to create cuda device: -1313558101.
[1584433298.497|21247|accel.cpp :163 ] -- Init failed for cuda with device 1.
[1584433298.497|21247|accel.cpp :153 ] -- Init encoding for cuda with device 2.
[AVHWDeviceContext @ 0x55bec3061ac0] Cannot load libcuda.so.1
[AVHWDeviceContext @ 0x55bec3061ac0] Could not dynamically load CUDA
[1584433298.498|21247|accel.cpp :102 ] Failed to create cuda device: -1313558101.
[1584433298.498|21247|accel.cpp :163 ] -- Init failed for cuda with device 2.
[1584433298.510|21247|accel.cpp :139 ] -- Starting encoding test for vaapi with default device.
[1584433298.513|21247|accel.cpp :114 ] Device type vaapi successfully created.
[1584433298.513|21247|accel.cpp :145 ] -- Init passed for vaapi with default device.
[1584433298.519|21247|system_codec_container.cpp:143 ] Found a usable accelerated H265/HEVC codec: vaapi, enabling.
[1584433298.520|21247|system_codec_container.cpp:181 ] Encoders found: H265 H264 VP8 MP4V-ES H263-1998 opus G722 speex speex speex PCMA PCMU
[1584433298.520|21247|system_codec_container.cpp:182 ] Decoders found: H265 H264 VP8 MP4V-ES H263-1998 opus G722 speex speex speex PCMA PCMU
[1584433298.520|21247|sipvoiplink.cpp :550 ] Using SIP nameserver: 192.168.0.10
[1584433298.520|21247|sipvoiplink.cpp :550 ] Using SIP nameserver: 127.0.0.53
[1584433298.520|21247|siptransport.cpp :188 ] SipTransportBroker@0x55bec3060db0
[1584433298.521|21247|sipvoiplink.cpp :641 ] SIPVoIPLink@0x55bec30604e0
[1584433298.521|21247|sipaccount.cpp :2191 ] All audio codecs disabled, enabling all
[1584433298.521|21247|sipaccount.cpp :2195 ] All video codecs disabled, enabling all
[1584433298.521|21251|sip_utils.cpp :203 ] Registered thread 0x7fd17bf564f0 (0x52FF)
[1584433298.524|21247|pupnp.cpp :111 ] PUPnP: Initialiazed on 192.168.1.107:0
[1584433298.525|21247|sipaccount.cpp :1678 ] Presence enabled for 63e40ec72311e5b1 : false.
[1584433298.529|21247|pulselayer.cpp :136 ] Waiting....
[1584433298.531|21264|pulselayer.cpp :136 ] Waiting....
[1584433298.532|21264|pulselayer.cpp :136 ] Waiting....
[1584433298.532|21264|pulselayer.cpp :140 ] Connection to PulseAudio server established
[1584433298.533|21264|pulselayer.cpp :169 ] Updating PulseAudio sink list
[1584433298.533|21264|pulselayer.cpp :185 ] Updating PulseAudio source list
[1584433298.533|21264|pulselayer.cpp :201 ] Updating PulseAudio server infos
[1584433298.534|21264|pulselayer.cpp :603 ] PulseAudio server info:
Server name: pulseaudio
Server version: 11.1
Default Sink alsa_output.pci-0000_00_1f.3.analog-stereo
Default Source alsa_input.pci-0000_00_1f.3.analog-stereo
Default Sample Specification: s16le 2ch 44100Hz
Default Channel Map: front-left,front-right
[1584433298.538|21252|nat_pmp.cpp :53 ] NAT-PMP: Initialized on gateway 192.168.1.1
[1584433298.578|21247|sipaccount.cpp :728 ] doRegister 192.168.0.212:5062
[1584433298.578|21247|sipaccount.cpp :733 ] UPnP: waiting for IGD to register SIP account
[1584433298.578|21247|upnp_context.cpp :289 ] UPnP: Trying to add mapping 62567:5060 UDP with no Internet Gateway Device available
[1584433298.578|21247|upnp_context.cpp :488 ] [upnp:controller@94277804156544] registering cb for mapping 62567:5060 UDP
[1584433299.578|21248|upnp_context.cpp :453 ] UPnPContext: Add mapping request for 62567:5060 UDP timed out
[1584433299.578|21248|sipaccount.cpp :704 ] [Account 63e40ec72311e5b1] Failed to open port 5060: registering SIP account anyways
[1584433299.578|21248|sipvoiplink.cpp :1218 ] try to resolve '192.168.0.212:5062' (port: 5062)
[1584433299.578|21248|sipaccount.cpp :825 ] Creating transport
[1584433299.578|21248|siptransport.cpp :340 ] Created UDP transport on address 0.0.0.0:5060
[1584433299.579|21248|siptransport.cpp :76 ] SipTransport@0x7fd13c001c50 {tr=0x7fd13c001428 {rc=2}}
[1584433300.524|21251|sipvoiplink.cpp :676 ] username = 202, server = 192.168.0.212, from = 192.168.0.212
[1584433300.524|21251|sipaccount.cpp :1723 ] Matching account id in request with username 202
[1584433300.567|21251|sipaccount.cpp :1872 ] IP address change detected for account 63e40ec72311e5b1 (192.168.100.156:5060 --> 192.168.0.10:62567). Updating registration (using method 2)
[1584433300.567|21251|sipaccount.cpp :1072 ] Contact overwritten
[1584433300.567|21251|message_engine.cpp:226 ] [Account 63e40ec72311e5b1] couldn't load messages from /home/user/.cache/jami/63e40ec72311e5b1/messages: basic_ios::clear: iostream error
[1584433300.568|21251|sipvoiplink.cpp :676 ] username = 202, server = 192.168.0.212, from = 192.168.0.212
[1584433300.568|21251|sipaccount.cpp :1723 ] Matching account id in request with username 202
[1584433300.585|21251|sipvoiplink.cpp :676 ] username = 202, server = 192.168.0.212, from = 192.168.0.212
[1584433300.585|21251|sipaccount.cpp :1723 ] Matching account id in request with username 202
[1584433304.173|21261|pupnp.cpp :275 ] PUPnP: Validating IGD candidate
UDN : uuid:20809696-105a-3721-e8b8-5078b3bf9c84
Name : UPNP IGD
Service Type : urn:schemas-upnp-org:service:WANIPConnection:1
Service ID : urn:upnp-org:serviceId:WANIPConn1
Base URL : http://192.168.1.254:52869
Location URL : http://192.168.1.254:52869/gatedesc.xml
control URL : http://192.168.1.254:52869/upnp/control/WANIPConn1
Event URL : http://192.168.1.254:52869/upnp/control/WANIPConn1
[1584433304.189|21261|pupnp.cpp :297 ] PUPnP: Found device with external IP 185.239.XXX.XXX
[1584433304.232|21261|upnp_context.cpp :214 ] UPnP: IGD with public IP 185.239.XXX.XXX was added to the list
[1584433304.232|21261|upnp_context.cpp :219 ] [upnp:controller@94277804156544] sending out request in cb queue for mapping 62567:5060 UDP
[1584433304.232|21261|pupnp.cpp :326 ] PUPnP: Attempting to open port 62567:5060 UDP
[1584433304.232|21261|pupnp.cpp :354 ] PUPnP: Sent request to open port 62567:5060 UDP
[1584433304.232|21261|pupnp.cpp :308 ] PUPnP: IGD with public IP 185.239.XXX.XXX was added to the list
[1584433304.245|21299|pupnp.cpp :621 ] PUPnP: Error when trying to handle subscription callback -> UPNP_E_SUBSCRIBE_UNACCEPTED
[1584433304.247|21254|pupnp.cpp :379 ] PUPnP: Opened port 62567:5060 UDP
[1584433304.247|21254|sipaccount.cpp :712 ] [Account 63e40ec72311e5b1] SIP port changed to 62567: reregistering SIP account
[1584433304.247|21254|sipvoiplink.cpp :1218 ] try to resolve '192.168.0.212:5062' (port: 5062)
[1584433304.247|21254|sipaccount.cpp :825 ] Creating transport
[1584433304.248|21254|siptransport.cpp :89 ] ~SipTransport@0x7fd13c001c50 {tr=0x7fd13c001428 {rc=9}}
[1584433304.248|21254|siptransport.cpp :304 ] Recycling transport 0.0.0.0:5060
[1584433304.248|21254|siptransport.cpp :76 ] SipTransport@0x7fd1380024e0 {tr=0x7fd13c001428 {rc=9}}
[1584433304.248|21254|sipaccount.cpp :960 ] Setting VIA sent-by to 192.168.0.10:62567
[1584433304.248|21263|message_engine.cpp:280 ] [Account 63e40ec72311e5b1] Couldn't save messages to /home/user/.cache/jami/63e40ec72311e5b1/messages: basic_ios::clear: iostream error
[1584433304.248|21263|message_engine.cpp:282 ] [Account 63e40ec72311e5b1] saved 0 messages to /home/user/.cache/jami/63e40ec72311e5b1/messages
[1584433304.248|21254|upnp_context.cpp :499 ] [upnp:controller@94277804156544] unregistering cb for mapping 62567:5060 UDP
[1584433304.326|21251|message_engine.cpp:226 ] [Account 63e40ec72311e5b1] couldn't load messages from /home/user/.cache/jami/63e40ec72311e5b1/messages: basic_ios::clear: iostream error
[1584433304.360|21251|sipvoiplink.cpp :676 ] username = 202, server = 192.168.0.212, from = 192.168.0.212
[1584433304.360|21251|sipaccount.cpp :1723 ] Matching account id in request with username 202
[1584433316.563|21247|manager.cpp :914 ] try outgoing call to '+34656870XXX' with account '63e40ec72311e5b1'
[1584433316.563|21247|sipaccount.cpp :190 ] [Account 63e40ec72311e5b1] Calling SIP peer +34656870XXX
[1584433316.563|21247|ringbufferpool.cpp:118 ] Ringbuffer created with id '901617757861941'
[1584433316.563|21247|sipaccount.cpp :218 ] UserAgent: New registered account call to +34656870XXX
[1584433316.563|21247|sipcall.cpp :1346 ] [call:901617757861941] create media ICE transport
[1584433316.563|21247|ice_transport.cpp :211 ] [ice] added host stun server
[1584433316.563|21247|ice_transport.cpp :211 ] [ice] added host stun server
[1584433316.564|21247|ice_transport.cpp :517 ] [ice:0x55bec3031ca0] initialization success
[1584433316.564|21247|ice_transport.cpp :570 ] ICE as master
[1584433316.564|21247|ice_transport.cpp :664 ] [ice:0x55bec3031ca0] (local) ufrag=29fa4fc0, pwd=1001448d09683e4023f9faf4
[1584433316.564|21247|ice_transport.cpp :805 ] [ice:0x55bec3031ca0] UPnP: Trying to open port 46095 for ICE comp 1/4 and adding candidate with public IP
[1584433316.564|21247|upnp_context.cpp :488 ] [upnp:controller@94277803985552] registering cb for mapping 46095:46095 UDP
[1584433316.564|21247|pupnp.cpp :326 ] PUPnP: Attempting to open port 46095:46095 UDP
[1584433316.564|21247|pupnp.cpp :354 ] PUPnP: Sent request to open port 46095:46095 UDP
[1584433316.564|21247|ice_transport.cpp :805 ] [ice:0x55bec3031ca0] UPnP: Trying to open port 34426 for ICE comp 2/4 and adding candidate with public IP
[1584433316.564|21247|upnp_context.cpp :488 ] [upnp:controller@94277803985552] registering cb for mapping 34426:34426 UDP
[1584433316.564|21247|pupnp.cpp :326 ] PUPnP: Attempting to open port 34426:34426 UDP
[1584433316.564|21247|pupnp.cpp :354 ] PUPnP: Sent request to open port 34426:34426 UDP
[1584433316.565|21247|ice_transport.cpp :805 ] [ice:0x55bec3031ca0] UPnP: Trying to open port 59925 for ICE comp 3/4 and adding candidate with public IP
[1584433316.565|21247|upnp_context.cpp :488 ] [upnp:controller@94277803985552] registering cb for mapping 59925:59925 UDP
[1584433316.565|21247|pupnp.cpp :326 ] PUPnP: Attempting to open port 59925:59925 UDP
[1584433316.565|21247|pupnp.cpp :354 ] PUPnP: Sent request to open port 59925:59925 UDP
[1584433316.565|21247|ice_transport.cpp :805 ] [ice:0x55bec3031ca0] UPnP: Trying to open port 40767 for ICE comp 4/4 and adding candidate with public IP
[1584433316.565|21247|upnp_context.cpp :488 ] [upnp:controller@94277803985552] registering cb for mapping 40767:40767 UDP
[1584433316.565|21247|pupnp.cpp :326 ] PUPnP: Attempting to open port 40767:40767 UDP
[1584433316.565|21247|pupnp.cpp :354 ] PUPnP: Sent request to open port 40767:40767 UDP
[1584433316.565|21405|sip_utils.cpp :203 ] Registered thread 0x7fd1497fa4f0 (0x52FF)
[1584433316.565|21247|sdp.cpp :363 ] SDP: Local SDP Session:
v=0
o=userPortatil 3793422116 0 IN IP4 185.239.XXX.XXX
s=Jami Daemon
c=IN IP4 185.239.XXX.XXX
t=0 0
m=audio 26092 RTP/AVP 9 112 110 8 0 104 101
a=rtpmap:9 G722/8000
a=rtpmap:112 speex/32000
a=rtpmap:110 speex/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 opus/48000/2
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:26093 IN IP4 185.239.XXX.XXX
a=sendrecv
m=video 53566 RTP/AVP 96 97 98 99
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428029
a=rtpmap:97 VP8/90000
a=rtpmap:98 MP4V-ES/90000
a=rtpmap:99 H263-1998/90000
a=sendrecv
[1584433316.565|21247|manager.cpp :561 ] ----- Switch current call id to '901617757861941' -----
[1584433316.565|21248|sipcall.cpp :852 ] [call:901617757861941] fill SDP with ICE transport 0x55bec2fedae0
[1584433316.565|21248|sipaccount.cpp :357 ] contact header: <sip:202@192.168.0.10:62567> / "SIP" <sip:202@192.168.0.212:5062> -> <sip:+34656870XXX@192.168.0.212:5062>
[1584433316.565|21248|sipvoiplink.cpp :794 ] [call:901617757861941] INVITE@0x7fd13c004558 state changed to 1 (CALLING): cause=0, tsx@0x7fd13c00bc08 status 0 (Default status message)
[1584433316.565|21248|call.cpp :218 ] [call:901617757861941] state change 0/1, cnx 0/2, code 0
[1584433316.565|21248|call.cpp :241 ] [call:901617757861941] emit client call state change CONNECTING, code 0
[1584433316.580|21402|pupnp.cpp :379 ] PUPnP: Opened port 34426:34426 UDP
[1584433316.580|21402|sip_utils.cpp :203 ] Registered thread 0x7fd17a7534f0 (0x52FF)
[1584433316.580|21402|ice_transport.cpp :767 ] [ice:0x55bec3031ca0] succeed to add candidate for comp_id=2 : 192.168.100.156 : 185.239.XXX.XXX
[1584433316.580|21402|upnp_context.cpp :499 ] [upnp:controller@94277803985552] unregistering cb for mapping 34426:34426 UDP
[1584433316.585|21299|pupnp.cpp :379 ] PUPnP: Opened port 46095:46095 UDP
[1584433316.585|21299|sip_utils.cpp :203 ] Registered thread 0x7fd1737fe4f0 (0x52FF)
[1584433316.585|21299|ice_transport.cpp :767 ] [ice:0x55bec3031ca0] succeed to add candidate for comp_id=1 : 192.168.100.156 : 185.239.XXX.XXX
[1584433316.585|21299|upnp_context.cpp :499 ] [upnp:controller@94277803985552] unregistering cb for mapping 46095:46095 UDP
[1584433316.946|21403|pupnp.cpp :379 ] PUPnP: Opened port 59925:59925 UDP
[1584433316.946|21404|pupnp.cpp :379 ] PUPnP: Opened port 40767:40767 UDP
[1584433316.946|21403|sip_utils.cpp :203 ] Registered thread 0x7fd14a7fc4f0 (0x52FF)
[1584433316.946|21403|ice_transport.cpp :767 ] [ice:0x55bec3031ca0] succeed to add candidate for comp_id=3 : 192.168.100.156 : 185.239.XXX.XXX
[1584433316.946|21403|upnp_context.cpp :499 ] [upnp:controller@94277803985552] unregistering cb for mapping 59925:59925 UDP
[1584433316.946|21404|sip_utils.cpp :203 ] Registered thread 0x7fd149ffb4f0 (0x52FF)
[1584433316.946|21404|ice_transport.cpp :767 ] [ice:0x55bec3031ca0] succeed to add candidate for comp_id=4 : 192.168.100.156 : 185.239.XXX.XXX
[1584433316.946|21404|upnp_context.cpp :499 ] [upnp:controller@94277803985552] unregistering cb for mapping 40767:40767 UDP
[1584433331.565|21251|sipvoiplink.cpp :794 ] [call:901617757861941] INVITE@0x7fd13c004558 state changed to 6 (DISCONNCTD): cause=408, tsx@0x7fd13c00bc08 status 408 (Request Timeout)
[1584433331.566|21251|call.cpp :218 ] [call:901617757861941] state change 1/5, cnx 2/0, code 408
[1584433331.566|21251|call.cpp :241 ] [call:901617757861941] emit client call state change FAILURE, code 408
[1584433331.566|21248|manager.cpp :1970 ] [call:901617757861941] Failed
[1584433331.566|21248|manager.cpp :1595 ] [call:901617757861941] Remove local audio
[1584433331.566|21248|sipcall.cpp :745 ] [call:901617757861941] removeCall()
[1584433331.566|21248|call_factory.cpp :39 ] Removing call 901617757861941
[1584433331.566|21248|call_factory.cpp :43 ] Remaining 0 SIP call(s)
[1584433331.566|21248|call.cpp :218 ] [call:901617757861941] state change 5/6, cnx 0/0, code 0
[1584433331.566|21248|call.cpp :241 ] [call:901617757861941] emit client call state change OVER, code 0
[1584433331.566|21248|ice_transport.cpp :405 ] [ice:0x55bec3031ca0] destroying
[1584433331.566|21248|sip_utils.cpp :203 ] Registered thread 0x7fd17ed134f0 (0x52FF)
[1584433332.080|21248|upnp_control.cpp :38 ] Destroying UPnP Controller 0x55bec3044a90
[1584433332.080|21248|pupnp.cpp :430 ] PUPnP: Attempting to close port 34426 UDP
[1584433332.081|21248|pupnp.cpp :1009 ] PUPnP: Sent request to close port 34426 UDP
[1584433332.081|21248|pupnp.cpp :430 ] PUPnP: Attempting to close port 40767 UDP
[1584433332.082|21248|pupnp.cpp :1009 ] PUPnP: Sent request to close port 40767 UDP
[1584433332.082|21248|pupnp.cpp :430 ] PUPnP: Attempting to close port 46095 UDP
[1584433332.082|21248|pupnp.cpp :1009 ] PUPnP: Sent request to close port 46095 UDP
[1584433332.082|21248|pupnp.cpp :430 ] PUPnP: Attempting to close port 59925 UDP
[1584433332.083|21248|pupnp.cpp :1009 ] PUPnP: Sent request to close port 59925 UDP
[1584433332.083|21248|upnp_control.cpp :38 ] Destroying UPnP Controller 0x55bec3074780
[1584433332.094|21403|pupnp.cpp :408 ] PUPnP: Closed port 34426 UDP
[1584433332.096|21462|pupnp.cpp :408 ] PUPnP: Closed port 40767 UDP
[1584433332.101|21463|pupnp.cpp :408 ] PUPnP: Closed port 46095 UDP
[1584433332.614|21464|pupnp.cpp :408 ] PUPnP: Closed port 59925 UDP
And this in the FreePBX side:
sip*CLI> pjsip show history
No. Timestamp (Dir) Address SIP Message
===== ========== ============================== ===================================
00000 1584433296 * ==> 192.168.0.10:62567 OPTIONS sip:202@192.168.0.10:62567 SIP/2.0
00001 1584433299 * <== 192.168.0.10:62567 REGISTER sip:192.168.0.212:5062 SIP/2.0
00002 1584433299 * ==> 192.168.0.10:62567 SIP/2.0 401 Unauthorized
00003 1584433300 * ==> 192.168.0.10:62567 OPTIONS sip:202@192.168.0.10:62567 SIP/2.0
00004 1584433300 * <== 192.168.0.10:62567 REGISTER sip:192.168.0.212:5062 SIP/2.0
00005 1584433300 * ==> 192.168.0.10:62567 SIP/2.0 401 Unauthorized
00006 1584433300 * <== 192.168.0.10:62567 REGISTER sip:192.168.0.212:5062 SIP/2.0
00007 1584433300 * <== 192.168.0.10:62567 SIP/2.0 200 OK
00008 1584433300 * ==> 192.168.0.10:62567 SIP/2.0 200 OK
00009 1584433300 * ==> 192.168.0.10:62567 OPTIONS sip:202@192.168.0.10:62567 SIP/2.0
00010 1584433300 * ==> 192.168.0.10:62567 NOTIFY sip:202@192.168.0.10:62567 SIP/2.0
00011 1584433300 * <== 192.168.0.10:62567 SIP/2.0 200 OK
00012 1584433300 * <== 192.168.0.10:62567 SIP/2.0 200 OK
00013 1584433304 * <== 192.168.0.10:62567 REGISTER sip:192.168.0.212:5062 SIP/2.0
00014 1584433304 * ==> 192.168.0.10:62567 SIP/2.0 401 Unauthorized
00015 1584433304 * <== 192.168.0.10:62567 REGISTER sip:192.168.0.212:5062 SIP/2.0
00016 1584433304 * ==> 192.168.0.10:62567 SIP/2.0 200 OK
00017 1584433304 * ==> 192.168.0.10:62567 NOTIFY sip:202@192.168.0.10:62567 SIP/2.0
00018 1584433304 * <== 192.168.0.10:62567 SIP/2.0 200 OK
00019 1584433316 * <== 192.168.100.156:5060 INVITE sip:+34656870XXX@192.168.0.212:5062 SIP/2.0
00020 1584433316 * ==> 192.168.100.156:5060 SIP/2.0 401 Unauthorized
00021 1584433317 * <== 192.168.100.156:5060 INVITE sip:+34656870XXX@192.168.0.212:5062 SIP/2.0
00022 1584433317 * ==> 192.168.100.156:5060 SIP/2.0 401 Unauthorized
00023 1584433318 * <== 192.168.100.156:5060 INVITE sip:+34656870XXX@192.168.0.212:5062 SIP/2.0
00024 1584433318 * ==> 192.168.100.156:5060 SIP/2.0 401 Unauthorized
00025 1584433320 * <== 192.168.100.156:5060 INVITE sip:+34656870XXX@192.168.0.212:5062 SIP/2.0
00026 1584433320 * ==> 192.168.100.156:5060 SIP/2.0 401 Unauthorized
00027 1584433324 * <== 192.168.100.156:5060 INVITE sip:+34656870XXX@192.168.0.212:5062 SIP/2.0
00028 1584433324 * ==> 192.168.100.156:5060 SIP/2.0 401 Unauthorized
IPs and ranges are as follow:
-
192.168.0.0/24
is office local network, where PBX is-
192.168.0.10
is VPN server -
192.168.0.212
is FreePBX
-
-
192.168.100.0/24
is office local network VPN addresses-
192.168.100.156
is Ubuntu client assigned IP through VPN
-
-
192.168.1.0/24
is external testing lab (my house :))-
192.168.1.107
is Ubuntu client assigned IP -
192.168.1.254
is testing lab router
-
-
185.239.XXX.XXX
is ISP on testing lab assigned IP
Feel free to suggest or advice anything in order to test this issue.