TLS and webrtc enablement

Stephen Tyers · Feb 12, 2017

Hi,

Has anyone got a definitive guide on how to enable secure SIP and also webrtc on Fusionpbx. It's not covered off in the Fusionpbx documentation and i'm struggling with the freeswitch wiki. I have managed to install letencrypt SSL certificates following the Fusionpbx Documentation, but I assume that's only on the NGINX webserver.
I really need some help please as I would like to securely register extensions and encrypt the RTP traffic. Also is it easy to get webrtc going on Fusionpbx? I've been playing with the Verto demo for Freeswitch on a VM and it's awesome. Thanks for the help in advance, i'm a real newbie to Linux in general so any dummies guide would be greatly appreciated.
Just out of interest what's the Fusionpbx config directory? On Freeswitch i'm use to /usr/local/freeswitch/conf, but on Fusionpbx there seems to be several.

Kind regards
Steve

DigitalDaz · Feb 12, 2017

I haven't tried webrtc yet really, hopefully someone will come along and help, if not I may give it a shot.

Fusionpbx now sticks the config in /etc/fusionpbx and /etc/freeswitch but depends on the config you are looking for. Most of it is served up via lua directly from the db.

Stephen Tyers · Feb 12, 2017

Thanks for the quick response. That makes sense actually that most of the config must be in the DB compared to a regular freeswitch install xml files.
Aside from the webrtc, the main thing i want to get working is the TLS for secure sip and RTP. Any ideas around that please?? I gather i have to set up some certificates and enables some interfaces??
Many thanks
Steve

TheOperator · Feb 14, 2017

Hi Steve,

freeswitch provides some very good documentation on how to generate the required certificates with the "gentls_cert" utility provided by freeswitch: https://freeswitch.org/confluence/display/FREESWITCH/SIP+TLS . You can use other certificates if you wish.

Oce you have the certificates you must place them in the "/etc/freeswitch/ssl" directory and make shure that the user www-data has read access to them.

Then you can activate TLS/SSL for your profiles by setting the option "[profile]_ssl_enable} to true in the "advanced -> variables" menu:

These are my settings for the external profile:

Note1: I am using a subdirectory under "\etc\freeswitch\ssl" for each profile as this is the only way to use different cafile.pem and agent.pem files per profile.
You can adjust the settings in the "advanced -> variables" menu as seen above.
Note2: Certificate verification is disabled by default - thus you do not need the CA or intermediate CA certificates from your provider to start.

By default, freeswitch will accept TLS requests but not initiate TLS sessions. To enable TLS session initiation, you need to add the "rtp_secure_media" options to your global settings or use an export statement in the outbound routes.

I opted for the "global" settings.
My current settings are (Please ignore the "rtp_secure_media_suites" as they are broken) :

This gives me optional TLS outbound and inbound sessions with TLSv1, 1.1 and 1.2.

Regards,
Olaf

Stephen Tyers · Feb 14, 2017

Hi Olaf,

I will give this a shot. Many thanks for the detailed description and screenshots

Cheers
Steve

Stephen Tyers · Feb 27, 2017

Hi Olaf,

I've decided to go back to basics and get TLS/SRTP working first on a plain freeswitch install before tackling fusionpbx and webrtc. I decided to use a commercial certificate from godaddy rather than a self signed.
I ran the script at the bottom of this page to generate the private key and CSR. I upload this and then downloaded my certificate and chain file from godaddy. I know i am supposed to concatenate either the private key, cert and maybe chain into certain pem files, but i can't find the definitive guide to do this and i'm very confused.
Would you be able to let me know what files i need to generate/combine from what and which folders to place them in please? I enabled tls in the vars.xml and I've been playing around but keep getting certificate mismatches when trying to connect clients. I get an error from this openssl test command, but Sofia status shows TLS is up (see below).
Many thanks for your help

Steve

openssl s_client -showcerts -connect freeswitch.kreiosconsulting.co.uk:5061

CONNECTED(00000003)

139907950823056:error:14077410:SSL routines:SSL23_GET_SERVER_HELLO:sslv3 alert handshake failure:s23_clnt.c:757:

---

no peer certificate available

---

No client certificate CA names sent

---

SSL handshake has read 7 bytes and written 289 bytes

---

New, (NONE), Cipher is (NONE)

Secure Renegotiation IS NOT supported

Compression: NONE

Expansion: NONE

SSL-Session:

Protocol : TLSv1.2

Cipher : 0000

Session-ID:

Session-ID-ctx:

Master-Key:

Key-Arg : None

PSK identity: None

PSK identity hint: None

SRP username: None

Start Time: 1488237838

Timeout : 300 (sec)

Verify return code: 0 (ok)

---

sofia status looks good

Name Type Data State

=================================================================================================

external-ipv6 profile sip:mod_sofia@[::1]:5080 RUNNING (0)

external profile sip:mod_sofia@138.68.155.8:5080 RUNNING (0)

external::example.com gateway sip:joeuser@example.com NOREG

internal-ipv6 profile sip:mod_sofia@[::1]:5060 RUNNING (0)

internal-ipv6 profile sip:mod_sofia@[::1]:5061 RUNNING (0) (TLS)

freeswitch.kreiosconsulting.co.uk alias internal ALIASED

internal profile sip:mod_sofia@138.68.155.8:5060 RUNNING (0)

internal profile sip:mod_sofia@138.68.155.8:5061 RUNNING (0) (TLS)

=================================================================================================

$ PKI_HOME=/etc/ssl
$ PRIVATE_KEY_DIR=${PKI_HOME}/private
$ CERT_DIR=${PKI_HOME}/public
$ CSR_DIR=${PKI_HOME}/csr

$ MY_DOMAIN=example.org
$ sudo mkdir -p $PRIVATE_KEY_DIR
$ PRIVATE_KEY_PEM=${PRIVATE_KEY_DIR}/${MY_DOMAIN}-key.pem
$ CSR_PEM=${CSR_DIR}/${MY_DOMAIN}-csr.pem
$ sudo openssl genrsa -out ${PRIVATE_KEY_PEM} 2048
$ sudo chmod 0640 ${PRIVATE_KEY_PEM}
$ sudo chgrp ssl-cert ${PRIVATE_KEY_PEM}
$ sudo mkdir -p ${CSR_DIR} ${CERT_DIR}
$ sudo openssl req -new \
-key ${PRIVATE_KEY_PEM} \
-out ${CSR_PEM} \
-subj "/CN=${MY_DOMAIN}"
$ sudo cat ${CSR_PEM}

TheOperator · Feb 28, 2017

Hi Steve,

as far as I know, enabling ssl in Freeswitch only will not work. You have to set the parameters in FusionPBX.

The certificates must be placed in /etc/freeswitch/ssl. The names are hard coded :-(
The cafile.pem should contain the chain file from godaddy
The agent.pem file should contain your certificate including the private key (certificate first, followed by your private key).
If your private key is password protected you could either remove it using openssl commands or enter the key in the "tls-passphrase" setting found in the settings of each sip-profile.
The files must be readable by www-data.

Don't forget to add the godaddy keychain to your clients (phones) or turn of certificate validation.

Regards,
Olaf

Stephen Tyers · Feb 28, 2017

Thanks for the quick response again Olaf

I actually just built a standalone freeswitch to try and get this all working bare bones and truly understand how it all works. The screenshots I gave earlier are from that box.
I will give this a try
Cheers
Steve

Stephen Tyers · Feb 28, 2017

Hi Olaf,
Okay I've been trying all your advice with my fusionpbx build. I already had letsencrypt certs installed for the nginx webserver so i used those and created the agent.pem file. I then moved the agent.pem and cafile.pem into /etc/freeswitch/ssl/internal and /etc/freeswitch/ssl/external. I then configured the variables from your previous post on both Internal and external profiles and restarted. All looks good from the status. I successfully tested some internal calls between a TLS registered zoiper phone and my regular sip registered polycom. I tried dialing inbound through my provider voipms to both polycom and ZOIPER, again everything worked fine and I could see the encrypted media on the FS console. The only thing that doesn't work is outbound to the PSTN through voipms on either tls connected client or the regular sip client. The console says incompatible destination??? I have copied the console output right at the bottom.

Many thanks again for your help. I am getting there thanks to you.

Kind regards

Steve

Sofia status
p.p1 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #28fe14; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} span.s1 {font-variant-ligatures: no-common-ligatures} span.Apple-tab-span {white-space

re}

Name Type Data State

=================================================================================================

external-ipv6 profile sip:mod_sofia@[::1]:5080 RUNNING (0)

external-ipv6 profile sip:mod_sofia@[::1]:5081 RUNNING (0) (TLS)

external profile sip:mod_sofia@138.68.146.51:5080 RUNNING (0)

external profile sip:mod_sofia@138.68.146.51:5081 RUNNING (0) (TLS)

external::facdc7fb-6365-42a7-b45e-d9556d912667 gateway sip:203903_udemydemo@london.voip.ms REGED

external::d3a63910-d513-41e3-b1dd-80b704f61e09 gateway sip:2435056e0@sipgate.co.uk REGED

internal-ipv6 profile sip:mod_sofia@[::1]:5060 RUNNING (0)

internal-ipv6 profile sip:mod_sofia@[::1]:5061 RUNNING (0) (TLS)

internal profile sip:mod_sofia@138.68.146.51:5060 RUNNING (0)

internal profile sip:mod_sofia@138.68.146.51:5061 RUNNING (0) (TLS)

=================================================================================================

LOG DUMP
p.p1 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #34bbc8; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} p.p2 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #afad24; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} p.p3 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #d53bd3; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} p.p4 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #28fe14; background-color: #000000; background-color: rgba(0, 0, 0, 0.9); min-height: 14.0px} p.p5 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #34bc26; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} p.p6 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #28fe14; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} span.s1 {font-variant-ligatures: no-common-ligatures}

2017-02-28 12:16:15.209756 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1999@voice.kreiosconsulting.co.uk [fd97237b-cd03-4dba-9be0-14d64bd24b20]

2017-02-28 12:16:15.209756 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Running State Change CS_NEW (Cur 1 Tot 24)

2017-02-28 12:16:15.209756 [DEBUG] sofia.c:9746 sofia/internal/1999@voice.kreiosconsulting.co.uk receiving invite from 81.100.64.159:58310 version: 1.6.13 -21-e755b43 64bit

2017-02-28 12:16:15.209756 [DEBUG] sofia.c:9913 IP 81.100.64.159 Rejected by acl "domains". Falling back to Digest auth.

2017-02-28 12:16:15.209756 [DEBUG] sofia.c:2313 detaching session fd97237b-cd03-4dba-9be0-14d64bd24b20

2017-02-28 12:16:15.209756 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [02074741119@voice.kreiosconsulting.co.uk] from ip 81.100.64.159

2017-02-28 12:16:15.209756 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State NEW

2017-02-28 12:16:15.229719 [DEBUG] sofia.c:2421 Re-attaching to session fd97237b-cd03-4dba-9be0-14d64bd24b20

2017-02-28 12:16:15.249739 [DEBUG] sofia.c:9746 sofia/internal/1999@voice.kreiosconsulting.co.uk receiving invite from 81.100.64.159:58310 version: 1.6.13 -21-e755b43 64bit

2017-02-28 12:16:15.249739 [DEBUG] sofia.c:9913 IP 81.100.64.159 Rejected by acl "domains". Falling back to Digest auth.

2017-02-28 12:16:15.249739 [DEBUG] sofia.c:7014 Channel sofia/internal/1999@voice.kreiosconsulting.co.uk entering state [received][100]

2017-02-28 12:16:15.249739 [DEBUG] sofia.c:7024 Remote SDP:

v=0

o=Z 0 0 IN IP4 192.168.0.3

s=Z

c=IN IP4 192.168.0.3

t=0 0

m=audio 34734 RTP/SAVP 3 110 97 8 0 101

a=rtpmap:110 speex/8000

a=rtpmap:97 iLBC/8000

a=fmtp:97 mode=20

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=crypto:5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==

a=crypto:6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==

a=crypto:3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=

a=crypto:4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=

a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI

a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_256_GCM_8] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_128_GCM_8] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_NULL_AUTH] in [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1449 Unsupported Crypto [5 AES_256_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_256_GCM_8] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_128_GCM_8] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_NULL_AUTH] in [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1449 Unsupported Crypto [6 AES_256_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+6C/0X96excOw==]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_256_GCM_8] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_128_GCM_8] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_NULL_AUTH] in [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1449 Unsupported Crypto [3 AES_192_CM_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_256_GCM_8] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_128_GCM_8] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_NULL_AUTH] in [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1449 Unsupported Crypto [4 AES_192_CM_HMAC_SHA1_32 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDIkyP8uWyrz+4=]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1384 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1389 Found suite AES_CM_128_HMAC_SHA1_80

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1455 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:Zyq+WuOdXApAELqRi7AzbfSqUh3TlnLJ0h7nWPDI]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:Xvzk+T2hypdFt0AO2JPGjHpBWxKYepvMWZiBQzs7]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4399 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4399 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4399 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4344 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4260 Set telephone-event payload to 101@8000

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:3043 Set Codec sofia/internal/1999@voice.kreiosconsulting.co.uk GSM/8000 20 ms 160 samples 13200 bits 1 channels

2017-02-28 12:16:15.249739 [DEBUG] switch_core_codec.c:111 sofia/internal/1999@voice.kreiosconsulting.co.uk Original read codec set to GSM:3

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4603 Set telephone-event payload to 101@8000

2017-02-28 12:16:15.249739 [DEBUG] switch_core_media.c:4662 sofia/internal/1999@voice.kreiosconsulting.co.uk Set 2833 dtmf send payload to 101 recv payload to 101

2017-02-28 12:16:15.249739 [DEBUG] sofia.c:7413 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State Change CS_NEW -> CS_INIT

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Running State Change CS_INIT (Cur 1 Tot 24)

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State INIT

2017-02-28 12:16:15.249739 [DEBUG] mod_sofia.c:90 sofia/internal/1999@voice.kreiosconsulting.co.uk SOFIA INIT

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1999@voice.kreiosconsulting.co.uk Standard INIT

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State Change CS_INIT -> CS_ROUTING

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State INIT going to sleep

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Running State Change CS_ROUTING (Cur 1 Tot 24)

2017-02-28 12:16:15.249739 [DEBUG] switch_channel.c:2249 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Callstate Change DOWN -> RINGING

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State ROUTING

2017-02-28 12:16:15.249739 [DEBUG] mod_sofia.c:143 sofia/internal/1999@voice.kreiosconsulting.co.uk SOFIA ROUTING

2017-02-28 12:16:15.249739 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1999@voice.kreiosconsulting.co.uk Standard ROUTING

2017-02-28 12:16:15.249739 [INFO] mod_dialplan_xml.c:637 Processing 1999 <1999>->02074741119 in context voice.kreiosconsulting.co.uk

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk parsing [voice.kreiosconsulting.co.uk->user_exists] continue=true

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [user_exists] () =~ // break=on-false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE

2017-02-28 12:16:15.349713 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0d540b4930 Connected.

2017-02-28 12:16:15.349713 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0d540b4930 released.

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(user_exists=false)

2017-02-28 12:16:15.349713 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [user_exists]=[false]

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk parsing [voice.kreiosconsulting.co.uk->call-direction] continue=true

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk ANTI-Action set(call_direction=local)

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk parsing [voice.kreiosconsulting.co.uk->variables] continue=true

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [variables] () =~ // break=on-false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action export(origination_callee_id_name=${destination_number})

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk parsing [voice.kreiosconsulting.co.uk->user_record] continue=true

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [user_record] () =~ // break=on-false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE

2017-02-28 12:16:15.349713 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0d540b4930 Connected.

2017-02-28 12:16:15.369714 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0d540b4930 released.

2017-02-28 12:16:15.369714 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0d540b4930 Connected.

2017-02-28 12:16:15.369714 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0d540b4930 released.

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(user_record=)

2017-02-28 12:16:15.369714 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [user_record]=[UNDEF]

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(from_user_exists=true)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [from_user_exists]=[true]

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(from_user_record=)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [from_user_record]=[UNDEF]

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk parsing [voice.kreiosconsulting.co.uk->redial] continue=true

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [redial] destination_number(02074741119) =~ /^(redial|\*870)$/ break=on-true

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [redial] () =~ // break=never

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk parsing [voice.kreiosconsulting.co.uk->voipms.00] continue=false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (FAIL) [voipms.00] destination_number(02074741119) =~ /^00(.*)$/ break=on-false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk parsing [voice.kreiosconsulting.co.uk->voipms.0] continue=false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Regex (PASS) [voipms.0] destination_number(02074741119) =~ /^0(.*)$/ break=on-false

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(sip_h_X-accountcode=${accountcode})

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(call_direction=outbound)

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(hangup_after_bridge=true)

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(effective_caller_id_name=${outbound_caller_id_name})

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(effective_caller_id_number=${outbound_caller_id_number})

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(inherit_codec=true)

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(ignore_display_updates=true)

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(callee_id_number=2074741119)

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(continue_on_fail=true)

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action set(outbound_prefix=0) INLINE

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(outbound_prefix=0)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [outbound_prefix]=[0]

Dialplan: sofia/internal/1999@voice.kreiosconsulting.co.uk Action bridge(sofia/gateway/facdc7fb-6365-42a7-b45e-d9556d912667/442074741119)

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State Change CS_ROUTING -> CS_EXECUTE

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State ROUTING going to sleep

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Running State Change CS_EXECUTE (Cur 1 Tot 24)

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State EXECUTE

2017-02-28 12:16:15.389702 [DEBUG] mod_sofia.c:198 sofia/internal/1999@voice.kreiosconsulting.co.uk SOFIA EXECUTE

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1999@voice.kreiosconsulting.co.uk Standard EXECUTE

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(call_direction=local)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [call_direction]=[local]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk export(origination_callee_id_name=02074741119)

2017-02-28 12:16:15.389702 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[02074741119]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(RFC2822_DATE=Tue, 28 Feb 2017 12:16:15 +0000)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [RFC2822_DATE]=[Tue, 28 Feb 2017 12:16:15 +0000]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk hash(insert/voice.kreiosconsulting.co.uk-last_dial/1999/02074741119)

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(sip_h_X-accountcode=voice.kreiosconsulting.co.uk)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [sip_h_X-accountcode]=[voice.kreiosconsulting.co.uk]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(call_direction=outbound)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [call_direction]=[outbound]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(hangup_after_bridge=true)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [hangup_after_bridge]=[true]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(effective_caller_id_name=test)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [effective_caller_id_name]=[test]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(effective_caller_id_number=03308080072)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [effective_caller_id_number]=[03308080072]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(inherit_codec=true)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [inherit_codec]=[true]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(ignore_display_updates=true)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [ignore_display_updates]=[true]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(callee_id_number=2074741119)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [callee_id_number]=[2074741119]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk set(continue_on_fail=true)

2017-02-28 12:16:15.389702 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1999@voice.kreiosconsulting.co.uk [continue_on_fail]=[true]

EXECUTE sofia/internal/1999@voice.kreiosconsulting.co.uk bridge(sofia/gateway/facdc7fb-6365-42a7-b45e-d9556d912667/442074741119)

2017-02-28 12:16:15.389702 [DEBUG] switch_channel.c:1250 sofia/internal/1999@voice.kreiosconsulting.co.uk EXPORTING[export_vars] [domain_name]=[voice.kreiosconsulting.co.uk] to event

2017-02-28 12:16:15.389702 [DEBUG] switch_channel.c:1250 sofia/internal/1999@voice.kreiosconsulting.co.uk EXPORTING[export_vars] [origination_callee_id_name]=[02074741119] to event

2017-02-28 12:16:15.389702 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables

2017-02-28 12:16:15.389702 [NOTICE] switch_channel.c:1104 New Channel sofia/external/442074741119 [b497758f-c0dd-4d06-82ec-6c4a7941779e]

2017-02-28 12:16:15.389702 [DEBUG] mod_sofia.c:4766 (sofia/external/442074741119) State Change CS_NEW -> CS_INIT

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:584 (sofia/external/442074741119) Running State Change CS_INIT (Cur 2 Tot 25)

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:627 (sofia/external/442074741119) State INIT

2017-02-28 12:16:15.389702 [DEBUG] mod_sofia.c:90 sofia/external/442074741119 SOFIA INIT

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [1 AEAD_AES_256_GCM_8 inline:8Tr9XSVYD4WkyHvdtACok1Jcijq/HJ+GPIhG2PWxScazsl1C8sPZ5jzo6gs]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [1 AEAD_AES_256_GCM_8 inline

+1QtA5iz32mH75vfxwRnbchwsozSO55+5q7gsga19m3Pm8AIwvaOYvb0Wo]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [2 AEAD_AES_128_GCM_8 inline:qD0jp12hSPN8zOHN2YhBbEpbW+SjN7eTVtp1ng]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [2 AEAD_AES_128_GCM_8 inline:FwgSEUKeNbJlVoadxUGgVXkBewr0xaRHVOMXRw]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:hTGXgEAPj5gj5FbzP7OyF+Jrqk1UutKrdSyIJLAU0FDErTYtMGURKHUa8Q+GJA]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:8QKTYRDkrMYdTtbGbLXWTUaR827iVCp617jWvn7ksfnZS3mlqcTJqeKusmw5GA]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:BCJVidiblKUagWzolBGAKBaWdcjaMJN/F8OvL3TFl/whBvok+UM]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:2L/Xv+4vTZIwDahJ5OnVsaB/G4RBOdQ75ppFhmtdEmfCfEa2ouI]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:TtC4r2K/ZPXMQ5F0Lli8kFZjmNpj0vL0RmOTxnj1]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:EnDDPSLf5v9W9GOkaqwFZ4ClTN6cxIJKjEaWKxE+]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:eEldHlJG6dGZG6ajK9p1JdaWlyQs24y0eeTg75u3nqtmvKI+phhb5M+kcUplzw]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:jOYl7rM+gwk5cjcd+KDE9PPNIeap5E94y2P+SSaqu+DrntNuihOw5BZF9/inHA]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:xz8CqX6UXQAzhDAQ+Aew5Cf801m3j22B3GDD5bRbSeXWYA9hChc]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:09qPQqdGg1GndI5Qnh7cTyz0m+eJJ9pUqKiUWVZQTOO/Nfyb5LA]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline

b9exDjhhLE75o6+4UKe8blD1MnaaK9b9/wbKXnU]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:FaOGbJDciYqCBxVJ3N7IliD5XejvVzNLbzl+vCwj]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [9 AES_CM_128_NULL_AUTH inline:uRRVJ/+6tEdFTPQv0tSo8i3OLs1kCjX+d8JSeofq]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [9 AES_CM_128_NULL_AUTH inline:qLzJJvwBwauWOUv8F0q3h64yGUHUm44VZQRtGkZo]

2017-02-28 12:16:15.389702 [DEBUG] sofia_glue.c:1282 sofia/external/442074741119 sending invite version: 1.6.13 -21-e755b43 64bit

Local SDP:

v=0

o=FreeSWITCH 1488261645 1488261646 IN IP4 138.68.146.51

s=FreeSWITCH

c=IN IP4 138.68.146.51

t=0 0

m=audio 22530 RTP/SAVP 3 0 8 101 13

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=crypto:1 AEAD_AES_256_GCM_8 inline:8Tr9XSVYD4WkyHvdtACok1Jcijq/HJ+GPIhG2PWxScazsl1C8sPZ5jzo6gs

a=crypto:2 AEAD_AES_128_GCM_8 inline:qD0jp12hSPN8zOHN2YhBbEpbW+SjN7eTVtp1ng

a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:hTGXgEAPj5gj5FbzP7OyF+Jrqk1UutKrdSyIJLAU0FDErTYtMGURKHUa8Q+GJA

a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:BCJVidiblKUagWzolBGAKBaWdcjaMJN/F8OvL3TFl/whBvok+UM

a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:TtC4r2K/ZPXMQ5F0Lli8kFZjmNpj0vL0RmOTxnj1

a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:eEldHlJG6dGZG6ajK9p1JdaWlyQs24y0eeTg75u3nqtmvKI+phhb5M+kcUplzw

a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:xz8CqX6UXQAzhDAQ+Aew5Cf801m3j22B3GDD5bRbSeXWYA9hChc

a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline

b9exDjhhLE75o6+4UKe8blD1MnaaK9b9/wbKXnU

a=crypto:9 AES_CM_128_NULL_AUTH inline:uRRVJ/+6tEdFTPQv0tSo8i3OLs1kCjX+d8JSeofq

a=rtpmap:13 CN/8000

a=ptime:20

a=sendrecv

m=audio 22530 RTP/AVP 3 0 8 101 13

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=ptime:20

a=sendrecv

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:40 sofia/external/442074741119 Standard INIT

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:48 (sofia/external/442074741119) State Change CS_INIT -> CS_ROUTING

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:627 (sofia/external/442074741119) State INIT going to sleep

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:584 (sofia/external/442074741119) Running State Change CS_ROUTING (Cur 2 Tot 25)

2017-02-28 12:16:15.389702 [DEBUG] sofia.c:7014 Channel sofia/external/442074741119 entering state [calling][0]

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:643 (sofia/external/442074741119) State ROUTING

2017-02-28 12:16:15.389702 [DEBUG] mod_sofia.c:143 sofia/external/442074741119 SOFIA ROUTING

2017-02-28 12:16:15.389702 [DEBUG] switch_ivr_originate.c:67 (sofia/external/442074741119) State Change CS_ROUTING -> CS_CONSUME_MEDIA

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:643 (sofia/external/442074741119) State ROUTING going to sleep

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:584 (sofia/external/442074741119) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 25)

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:662 (sofia/external/442074741119) State CONSUME_MEDIA

2017-02-28 12:16:15.389702 [DEBUG] switch_core_state_machine.c:662 (sofia/external/442074741119) State CONSUME_MEDIA going to sleep

2017-02-28 12:16:15.509701 [DEBUG] sofia.c:7014 Channel sofia/external/442074741119 entering state [calling][0]

2017-02-28 12:16:15.509701 [DEBUG] sofia.c:7014 Channel sofia/external/442074741119 entering state [terminated][488]

2017-02-28 12:16:15.509701 [NOTICE] sofia.c:8141 Hangup sofia/external/442074741119 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:584 (sofia/external/442074741119) Running State Change CS_HANGUP (Cur 2 Tot 25)

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:850 (sofia/external/442074741119) Callstate Change DOWN -> HANGUP

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:852 (sofia/external/442074741119) State HANGUP

2017-02-28 12:16:15.509701 [DEBUG] mod_sofia.c:438 Channel sofia/external/442074741119 hanging up, cause: INCOMPATIBLE_DESTINATION

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:60 sofia/external/442074741119 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:852 (sofia/external/442074741119) State HANGUP going to sleep

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:619 (sofia/external/442074741119) State Change CS_HANGUP -> CS_REPORTING

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:584 (sofia/external/442074741119) Running State Change CS_REPORTING (Cur 2 Tot 25)

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:938 (sofia/external/442074741119) State REPORTING

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:174 sofia/external/442074741119 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:938 (sofia/external/442074741119) State REPORTING going to sleep

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:610 (sofia/external/442074741119) State Change CS_REPORTING -> CS_DESTROY

2017-02-28 12:16:15.509701 [DEBUG] switch_core_session.c:1647 Session 25 (sofia/external/442074741119) Locked, Waiting on external entities

2017-02-28 12:16:15.509701 [DEBUG] switch_ivr_originate.c:3829 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]

2017-02-28 12:16:15.509701 [INFO] mod_dptools.c:3409 Originate Failed. Cause: INCOMPATIBLE_DESTINATION

2017-02-28 12:16:15.509701 [NOTICE] switch_core_state_machine.c:385 sofia/internal/1999@voice.kreiosconsulting.co.uk has executed the last dialplan instruction, hanging up.

2017-02-28 12:16:15.509701 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/1999@voice.kreiosconsulting.co.uk [CS_EXECUTE] [NORMAL_CLEARING]

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State EXECUTE going to sleep

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Running State Change CS_HANGUP (Cur 2 Tot 25)

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Callstate Change RINGING -> HANGUP

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State HANGUP

2017-02-28 12:16:15.509701 [DEBUG] mod_sofia.c:432 sofia/internal/1999@voice.kreiosconsulting.co.uk Overriding SIP cause 480 with 488 from the other leg

2017-02-28 12:16:15.509701 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1999@voice.kreiosconsulting.co.uk hanging up, cause: NORMAL_CLEARING

2017-02-28 12:16:15.509701 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 488

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1999@voice.kreiosconsulting.co.uk Standard HANGUP, cause: NORMAL_CLEARING

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State HANGUP going to sleep

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State Change CS_HANGUP -> CS_REPORTING

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Running State Change CS_REPORTING (Cur 2 Tot 25)

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State REPORTING

2017-02-28 12:16:15.509701 [NOTICE] switch_core_session.c:1665 Session 25 (sofia/external/442074741119) Ended

2017-02-28 12:16:15.509701 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/442074741119 [CS_DESTROY]

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:741 (sofia/external/442074741119) Running State Change CS_DESTROY (Cur 1 Tot 25)

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:751 (sofia/external/442074741119) State DESTROY

2017-02-28 12:16:15.509701 [DEBUG] mod_sofia.c:343 sofia/external/442074741119 SOFIA DESTROY

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:181 sofia/external/442074741119 Standard DESTROY

2017-02-28 12:16:15.509701 [DEBUG] switch_core_state_machine.c:751 (sofia/external/442074741119) State DESTROY going to sleep

2017-02-28 12:16:15.589703 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1999@voice.kreiosconsulting.co.uk Standard REPORTING, cause: NORMAL_CLEARING

2017-02-28 12:16:15.589703 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State REPORTING going to sleep

2017-02-28 12:16:15.589703 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State Change CS_REPORTING -> CS_DESTROY

2017-02-28 12:16:15.589703 [DEBUG] switch_core_session.c:1647 Session 24 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Locked, Waiting on external entities

2017-02-28 12:16:15.589703 [NOTICE] switch_core_session.c:1665 Session 24 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Ended

2017-02-28 12:16:15.589703 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/1999@voice.kreiosconsulting.co.uk [CS_DESTROY]

2017-02-28 12:16:15.589703 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1999@voice.kreiosconsulting.co.uk) Running State Change CS_DESTROY (Cur 0 Tot 25)

2017-02-28 12:16:15.589703 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State DESTROY

2017-02-28 12:16:15.589703 [DEBUG] mod_sofia.c:343 sofia/internal/1999@voice.kreiosconsulting.co.uk SOFIA DESTROY

2017-02-28 12:16:15.589703 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1999@voice.kreiosconsulting.co.uk Standard DESTROY

2017-02-28 12:16:15.589703 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1999@voice.kreiosconsulting.co.uk) State DESTROY going to sleep

freeswitch@voice.kreiosconsulting.co.uk>

TheOperator · Feb 28, 2017

Hi Steve,

nice to hear that TLS/SRTP is working for the internal systems.

To get a better picture of the communication with your provider please enter the following commands on the fs_cli and retry an outbound call - one without TLS/SRTP and one with TLS/SRTP if possible:

/log warning
sofia profile external siptrace on

To reset everything back to default use:

/log debug
sofia profile external siptrace off

You can control the outbound srtp settings for a particular outbound route directly in the outbound dialplan with rtp_secure_media_outbound=true/false/optional for testing, if it is a srtp problem:

I had similar problems with one of my providers ...... took over 3 weeks to convince them that the problem was with their session boarder controller and to get that fixed.

Regards,
Olaf

Stephen Tyers · Feb 28, 2017

Hi Olaf,

I've just tried setting the rtp_secure_outbound=optional and run the log traces for first the regular polycom sip handset and then the tls ZOIPER, still not working(see below). I'm really stumped to be honest, especially with why both not working.

Many thanks
Steve

p.p1 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #28fe14; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} p.p2 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #d53bd3; background-color: #000000; background-color: rgba(0, 0, 0, 0.9)} p.p3 {margin: 0.0px 0.0px 0.0px 0.0px; font: 12.0px 'Andale Mono'; color: #28fe14; background-color: #000000; background-color: rgba(0, 0, 0, 0.9); min-height: 14.0px} span.s1 {font-variant-ligatures: no-common-ligatures}

This app Best viewed at 160x60 or more..]

+OK log level [7]

freeswitch@voice.kreiosconsulting.co.uk> /log warning

+OK log level warning [4]

freeswitch@voice.kreiosconsulting.co.uk> sofia profile external siptrace on

Enabled sip debugging on external

2017-02-28 15:13:29.368658 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [02074741119@voice.kreiosconsulting.co.uk] from ip 81.100.64.159

send 2258 bytes to udp/[159.8.157.212]:5060 at 15:13:30.344210:

------------------------------------------------------------------------

INVITE sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bK7DH1BH663ZmNm

Max-Forwards: 69

From: "test" <sip:203903_udemydemo@london.voip.ms>;tag=2B4SQaBKS582j

To: <sip:442074741119@london.voip.ms>

Call-ID: 4dca088e-786b-1235-86b7-5e5c946e5456

CSeq: 103811341 INVITE

Contact: <sip:gw+facdc7fb-6365-42a7-b45e-d9556d912667@138.68.146.51:5080;transport=udp;gw=facdc7fb-6365-42a7-b45e-d9556d912667>

User-Agent: FreeSWITCH

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY

Supported: timer, path, replaces

Allow-Events: talk, hold, conference, refer

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 1354

X-accountcode: voice.kreiosconsulting.co.uk

X-FS-Support: update_display,send_info

Remote-Party-ID: "test" <sip:03308080072@london.voip.ms>;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1488266259 1488266260 IN IP4 138.68.146.51

s=FreeSWITCH

c=IN IP4 138.68.146.51

t=0 0

m=audio 28550 RTP/SAVP 9 0 8 3 101 13

a=rtpmap:9 G722/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=crypto:1 AEAD_AES_256_GCM_8 inline:0zu4T3H20CD1sLwN+OKGIWi/eqC9aI/giOCdbmIbQY+/y1y0tLoMfq0n4cY

a=crypto:2 AEAD_AES_128_GCM_8 inline:e5EnrTjU5A58LOdsei38ooFeld40zkp/CRS+lg

a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:Qm8vGPhLvIGP9QGYDFZOesvxx7g3p2CBNEwIdZNmlaW7HPE/QKMMMrK0zF23Lg

a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:ZqGt4jg5b+VzMSjsWesnmIop6Y+0zlmLTRf+x3XKpY6NAlToES8

a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:jDW2S474im/Q+cr+fT3Hkq7ksOWZ7bYLvY1VR/wM

a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:myjoWoRLsQQE2Ak9nuP9amvRxkROrRQ9eoLwU1sWzf9TXR+Xm6tW/NvSnMDGJA

a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:ElXrCqVRrF9Na02IyWgP1sJWZFt0FK6PClia6rVOxr1gD6u34YE

a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:KBBJJJ15SbhHN7e5P/CyStBHuobcMAjXeOp3IgmD

a=crypto:9 AES_CM_128_NULL_AUTH inline:GHD2DC6gS+iqo5QlUtq3C213si+uqeayVJkbbz5Y

a=ptime:20

m=audio 28550 RTP/AVP 9 0 8 3 101 13

a=rtpmap:9 G722/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=ptime:20

------------------------------------------------------------------------

recv 561 bytes from udp/[159.8.157.212]:5060 at 15:13:30.347421:

------------------------------------------------------------------------

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 138.68.146.51:5080;branch=z9hG4bK7DH1BH663ZmNm;received=138.68.146.51;rport=5080

From: "test" <sip:203903_udemydemo@london.voip.ms>;tag=2B4SQaBKS582j

To: <sip:442074741119@london.voip.ms>;tag=as6c374c85

Call-ID: 4dca088e-786b-1235-86b7-5e5c946e5456

CSeq: 103811341 INVITE

Server: voip.ms

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE

Supported: replaces, timer

WWW-Authenticate: Digest algorithm=MD5, realm="london1.voip.ms", nonce="7229da8c"

Content-Length: 0

------------------------------------------------------------------------

send 347 bytes to udp/[159.8.157.212]:5060 at 15:13:30.347562:

------------------------------------------------------------------------

ACK sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bK7DH1BH663ZmNm

Max-Forwards: 69

From: "test" <sip:203903_udemydemo@london.voip.ms>;tag=2B4SQaBKS582j

To: <sip:442074741119@london.voip.ms>;tag=as6c374c85

Call-ID: 4dca088e-786b-1235-86b7-5e5c946e5456

CSeq: 103811341 ACK

Content-Length: 0

------------------------------------------------------------------------

send 2451 bytes to udp/[159.8.157.212]:5060 at 15:13:30.347965:

------------------------------------------------------------------------

INVITE sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bK8patDcQa18a8F

Max-Forwards: 69

From: "test" <sip:203903_udemydemo@london.voip.ms>;tag=2B4SQaBKS582j

To: <sip:442074741119@london.voip.ms>

Call-ID: 4dca088e-786b-1235-86b7-5e5c946e5456

CSeq: 103811342 INVITE

Contact: <sip:gw+facdc7fb-6365-42a7-b45e-d9556d912667@138.68.146.51:5080;transport=udp;gw=facdc7fb-6365-42a7-b45e-d9556d912667>

User-Agent: FreeSWITCH

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY

Supported: timer, path, replaces

Allow-Events: talk, hold, conference, refer

Authorization: Digest username="203903_udemydemo", realm="london1.voip.ms", nonce="7229da8c", algorithm=MD5, uri="sip:442074741119@london.voip.ms", response="06f3f71db59d2f589a060516e7d168e9"

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 1354

X-accountcode: voice.kreiosconsulting.co.uk

X-FS-Support: update_display,send_info

Remote-Party-ID: "test" <sip:03308080072@london.voip.ms>;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1488266259 1488266260 IN IP4 138.68.146.51

s=FreeSWITCH

c=IN IP4 138.68.146.51

t=0 0

m=audio 28550 RTP/SAVP 9 0 8 3 101 13

a=rtpmap:9 G722/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=crypto:1 AEAD_AES_256_GCM_8 inline:0zu4T3H20CD1sLwN+OKGIWi/eqC9aI/giOCdbmIbQY+/y1y0tLoMfq0n4cY

a=crypto:2 AEAD_AES_128_GCM_8 inline:e5EnrTjU5A58LOdsei38ooFeld40zkp/CRS+lg

a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:Qm8vGPhLvIGP9QGYDFZOesvxx7g3p2CBNEwIdZNmlaW7HPE/QKMMMrK0zF23Lg

a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:ZqGt4jg5b+VzMSjsWesnmIop6Y+0zlmLTRf+x3XKpY6NAlToES8

a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:jDW2S474im/Q+cr+fT3Hkq7ksOWZ7bYLvY1VR/wM

a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:myjoWoRLsQQE2Ak9nuP9amvRxkROrRQ9eoLwU1sWzf9TXR+Xm6tW/NvSnMDGJA

a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:ElXrCqVRrF9Na02IyWgP1sJWZFt0FK6PClia6rVOxr1gD6u34YE

a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:KBBJJJ15SbhHN7e5P/CyStBHuobcMAjXeOp3IgmD

a=crypto:9 AES_CM_128_NULL_AUTH inline:GHD2DC6gS+iqo5QlUtq3C213si+uqeayVJkbbz5Y

a=ptime:20

m=audio 28550 RTP/AVP 9 0 8 3 101 13

a=rtpmap:9 G722/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=ptime:20

------------------------------------------------------------------------

recv 485 bytes from udp/[159.8.157.212]:5060 at 15:13:30.350442:

------------------------------------------------------------------------

SIP/2.0 488 Not acceptable here

Via: SIP/2.0/UDP 138.68.146.51:5080;branch=z9hG4bK8patDcQa18a8F;received=138.68.146.51;rport=5080

From: "test" <sip:203903_udemydemo@london.voip.ms>;tag=2B4SQaBKS582j

To: <sip:442074741119@london.voip.ms>;tag=as6c374c85

Call-ID: 4dca088e-786b-1235-86b7-5e5c946e5456

CSeq: 103811342 INVITE

Server: voip.ms

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE

Supported: replaces, timer

Content-Length: 0

------------------------------------------------------------------------

send 347 bytes to udp/[159.8.157.212]:5060 at 15:13:30.350580:

------------------------------------------------------------------------

ACK sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bK8patDcQa18a8F

Max-Forwards: 69

From: "test" <sip:203903_udemydemo@london.voip.ms>;tag=2B4SQaBKS582j

To: <sip:442074741119@london.voip.ms>;tag=as6c374c85

Call-ID: 4dca088e-786b-1235-86b7-5e5c946e5456

CSeq: 103811342 ACK

Content-Length: 0

------------------------------------------------------------------------

2017-02-28 15:13:46.408654 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [02074741119@voice.kreiosconsulting.co.uk] from ip 81.100.64.159

send 2203 bytes to udp/[159.8.157.212]:5060 at 15:13:47.124816:

------------------------------------------------------------------------

INVITE sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bK9Z3jF77DyH1tB

Max-Forwards: 69

From: "2000" <sip:203903_udemydemo@london.voip.ms>;tag=3mXjS5UppeZNe

To: <sip:442074741119@london.voip.ms>

Call-ID: 57ca8d14-786b-1235-86b7-5e5c946e5456

CSeq: 103811349 INVITE

Contact: <sip:gw+facdc7fb-6365-42a7-b45e-d9556d912667@138.68.146.51:5080;transport=udp;gw=facdc7fb-6365-42a7-b45e-d9556d912667>

User-Agent: FreeSWITCH

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY

Supported: timer, path, replaces

Allow-Events: talk, hold, conference, refer

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 1306

X-accountcode: voice.kreiosconsulting.co.uk

X-FS-Support: update_display,send_info

Remote-Party-ID: "2000" <sip:2000@london.voip.ms>;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1488275524 1488275525 IN IP4 138.68.146.51

s=FreeSWITCH

c=IN IP4 138.68.146.51

t=0 0

m=audio 19302 RTP/SAVP 3 0 8 101 13

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=crypto:1 AEAD_AES_256_GCM_8 inline:K2yZ3CqP5pUpTwQtWA7NoYEqF3WoIOslQMS3t8hfPo7KuKpuSCn/FXLDEDY

a=crypto:2 AEAD_AES_128_GCM_8 inline:e4weWo4YU3ByNIpgH8NUcZ8tND7wN2aa5lndEA

a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:Ke/mu6eeqQHFUpvCHDfnZasKCnwDE77/Tth8sYAYrETDiWlq8rourdMhkBYnLQ

a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:ZdjzDr53EdBX6r0KHmXB4n3bd/AwSkbbTL4OiEQT3TpX1kAS1JA

a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:9nrCiyqgxTyXZcoZaK8qG3hlbZzvFk8ML59ePrSl

a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:6ROLCZogNBbMOjEENfRmwtlNUAtKiyvBoB+7+e79IzgxYoojJ8J9shfgSf91wg

a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:764IK0C7yOs1kovxYhJL0LqO0v34iqBNUIkpktFY2E9bQJHuO40

a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:FKnhcv0AvfU2dtJ5mkRnat5mmkFE2cbM3aNoiL30

a=crypto:9 AES_CM_128_NULL_AUTH inline:1ZQ0kFEUug6J82Webql23ZH5xdau6M1tjlBWzhxD

a=ptime:20

m=audio 19302 RTP/AVP 3 0 8 101 13

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=ptime:20

------------------------------------------------------------------------

recv 561 bytes from udp/[159.8.157.212]:5060 at 15:13:47.127607:

------------------------------------------------------------------------

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 138.68.146.51:5080;branch=z9hG4bK9Z3jF77DyH1tB;received=138.68.146.51;rport=5080

From: "2000" <sip:203903_udemydemo@london.voip.ms>;tag=3mXjS5UppeZNe

To: <sip:442074741119@london.voip.ms>;tag=as2c77b4e2

Call-ID: 57ca8d14-786b-1235-86b7-5e5c946e5456

CSeq: 103811349 INVITE

Server: voip.ms

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE

Supported: replaces, timer

WWW-Authenticate: Digest algorithm=MD5, realm="london1.voip.ms", nonce="7083472b"

Content-Length: 0

------------------------------------------------------------------------

send 347 bytes to udp/[159.8.157.212]:5060 at 15:13:47.127702:

------------------------------------------------------------------------

ACK sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bK9Z3jF77DyH1tB

Max-Forwards: 69

From: "2000" <sip:203903_udemydemo@london.voip.ms>;tag=3mXjS5UppeZNe

To: <sip:442074741119@london.voip.ms>;tag=as2c77b4e2

Call-ID: 57ca8d14-786b-1235-86b7-5e5c946e5456

CSeq: 103811349 ACK

Content-Length: 0

------------------------------------------------------------------------

send 2396 bytes to udp/[159.8.157.212]:5060 at 15:13:47.127952:

------------------------------------------------------------------------

INVITE sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bKa9vBH2rHUtQDQ

Max-Forwards: 69

From: "2000" <sip:203903_udemydemo@london.voip.ms>;tag=3mXjS5UppeZNe

To: <sip:442074741119@london.voip.ms>

Call-ID: 57ca8d14-786b-1235-86b7-5e5c946e5456

CSeq: 103811350 INVITE

Contact: <sip:gw+facdc7fb-6365-42a7-b45e-d9556d912667@138.68.146.51:5080;transport=udp;gw=facdc7fb-6365-42a7-b45e-d9556d912667>

User-Agent: FreeSWITCH

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY

Supported: timer, path, replaces

Allow-Events: talk, hold, conference, refer

Authorization: Digest username="203903_udemydemo", realm="london1.voip.ms", nonce="7083472b", algorithm=MD5, uri="sip:442074741119@london.voip.ms", response="336979c05f638db687e3d2c7e1aeb929"

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 1306

X-accountcode: voice.kreiosconsulting.co.uk

X-FS-Support: update_display,send_info

Remote-Party-ID: "2000" <sip:2000@london.voip.ms>;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1488275524 1488275525 IN IP4 138.68.146.51

s=FreeSWITCH

c=IN IP4 138.68.146.51

t=0 0

m=audio 19302 RTP/SAVP 3 0 8 101 13

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=crypto:1 AEAD_AES_256_GCM_8 inline:K2yZ3CqP5pUpTwQtWA7NoYEqF3WoIOslQMS3t8hfPo7KuKpuSCn/FXLDEDY

a=crypto:2 AEAD_AES_128_GCM_8 inline:e4weWo4YU3ByNIpgH8NUcZ8tND7wN2aa5lndEA

a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:Ke/mu6eeqQHFUpvCHDfnZasKCnwDE77/Tth8sYAYrETDiWlq8rourdMhkBYnLQ

a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:ZdjzDr53EdBX6r0KHmXB4n3bd/AwSkbbTL4OiEQT3TpX1kAS1JA

a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:9nrCiyqgxTyXZcoZaK8qG3hlbZzvFk8ML59ePrSl

a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:6ROLCZogNBbMOjEENfRmwtlNUAtKiyvBoB+7+e79IzgxYoojJ8J9shfgSf91wg

a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:764IK0C7yOs1kovxYhJL0LqO0v34iqBNUIkpktFY2E9bQJHuO40

a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:FKnhcv0AvfU2dtJ5mkRnat5mmkFE2cbM3aNoiL30

a=crypto:9 AES_CM_128_NULL_AUTH inline:1ZQ0kFEUug6J82Webql23ZH5xdau6M1tjlBWzhxD

a=ptime:20

m=audio 19302 RTP/AVP 3 0 8 101 13

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:13 CN/8000

a=ptime:20

------------------------------------------------------------------------

recv 485 bytes from udp/[159.8.157.212]:5060 at 15:13:47.130429:

------------------------------------------------------------------------

SIP/2.0 488 Not acceptable here

Via: SIP/2.0/UDP 138.68.146.51:5080;branch=z9hG4bKa9vBH2rHUtQDQ;received=138.68.146.51;rport=5080

From: "2000" <sip:203903_udemydemo@london.voip.ms>;tag=3mXjS5UppeZNe

To: <sip:442074741119@london.voip.ms>;tag=as2c77b4e2

Call-ID: 57ca8d14-786b-1235-86b7-5e5c946e5456

CSeq: 103811350 INVITE

Server: voip.ms

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE

Supported: replaces, timer

Content-Length: 0

------------------------------------------------------------------------

send 347 bytes to udp/[159.8.157.212]:5060 at 15:13:47.130507:

------------------------------------------------------------------------

ACK sip:442074741119@london.voip.ms SIP/2.0

Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bKa9vBH2rHUtQDQ

Max-Forwards: 69

From: "2000" <sip:203903_udemydemo@london.voip.ms>;tag=3mXjS5UppeZNe

To: <sip:442074741119@london.voip.ms>;tag=as2c77b4e2

Call-ID: 57ca8d14-786b-1235-86b7-5e5c946e5456

CSeq: 103811350 ACK

Content-Length: 0

------------------------------------------------------------------------

freeswitch@voice.kreiosconsulting.co.uk> sofia profile external siptrace off

Disabled sip debugging on external

freeswitch@voice.kreiosconsulting.co.uk>

TheOperator · Feb 28, 2017

Hi Steve,

beware that you are posting your provider data and phone numbers on a public forum .... it might be a good idea to obscure them.

I noticed two things in your trace:

You are not using TLS on the outbound connection:
Via: SIP/2.0/UDP 138.68.146.51:5080;rport;branch=z9hG4bK8patDcQa18a8F
Your "From" might be invalid - at least my providers will not accept anything like that:
From: "2000" <sip:203903_udemydemo@london.voip.ms>;tag=3mXjS5UppeZNe

Check the following settings in the advanced section of your gateway configuration:

Register Transport
Caller ID in From
Extension in Contact

Check what your provider specifies.

Also check the "Outbound Caller ID Name" and "Outbound Caller ID Number" settings of your extension - my providers require that both contain my oubound phone number.

It might be a good idea to pass the sip trace on to your provider to see what they say.

Regards,
Olaf

Stephen Tyers · Feb 28, 2017

Thanks Olaf,

I will give this ago. Yes good point about the SIP provider details, although it's just a test box, number and account.

Many thanks
Steve

bkcberry · Mar 9, 2018

hey guys, sorry to revive an old thread. Was just wondering if someone can point me to some instructions for setting up sip tls and srtp? I have a letsencrypt certificate set up and working for nginx, but from the bits and pieces of info i'm finding i can't get tls to work for sip.

phonesimon · Mar 9, 2018

Hey bkcberry, the FreeSWITCH wiki has a thorough guide: https://freeswitch.org/confluence/display/FREESWITCH/SIP+TLS

Where it says to modify the profiles, you would do this in Advanced - SIP Profiles or in the Variables section.

bkcberry · Mar 10, 2018

awesome, thanks!

one more question tho- can i use the letsencrypt certificates or do i actually need to generate my own? i assume i'd need to add some stuff to the renewal script to copy and rename the renewed certificate every 3 months. Just not sure if letsencrypt creates the type of cert that freeswitch needs

phonesimon · Mar 10, 2018

Let's Encrypt is fine if you're single-tenant. If you're multi-tenant you will likely need a wildcard cert which you would have to get from elsewhere. FreeSWITCH only lets you load one certificate per Sofia profile.

bkcberry · Mar 14, 2018

thanks again. for anyone else that stumbles upon this, i added privkey.pem to the end of fullchain.pem and saved the whole thing in /etc/freeswitch/tls as tls.pem and i'm now able to register and make calls using TLS and SRTP

i also added lines to the end of my cron job to automatically do that and reboot the server every few months when a new cert is generated

gin92 · May 30, 2018

bkcberry said:
thanks again. for anyone else that stumbles upon this, i added privkey.pem to the end of fullchain.pem and saved the whole thing in /etc/freeswitch/tls as tls.pem and i'm now able to register and make calls using TLS and SRTP

i also added lines to the end of my cron job to automatically do that and reboot the server every few months when a new cert is generated

Hello,good to know it works for you. I tried the same as you suggested but my registration still fails with tls. I have generated the certificates using letsencrypt and saved a file tls.pem with both fullchain.pem and privkey.pem.

Can you let me know the changes you made before or after this just in case i missed something?
I enabled internal_ssl_enable and internal_auth_calls with internal_ssl_dir set to $${conf_dir}/tls. Did the same for external profile too.Is there anything else i need to do in order to make it work? Suggestions would be of great help. Thanks

MTR · Nov 13, 2018

Thanks guys for all the above details

I am using alpha SSL wild card certificates

My yealinks phones work great any idea how I can get my polycom phones to work

Right now the polycom phones in the log file says can’t validate the ssl cert

TLS and webrtc enablement

New Member

Administrator

New Member

Member

New Member

New Member

Member

New Member

New Member

Member

New Member

Member

New Member

New Member

Member

New Member

Member

New Member

New Member

Member