I can't receive calls

[AverageGuy]

I'm installing a new version on Ubuntu and everything is working except incoming calls. The log shows:

649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:34:57.634389 99.33% [NOTICE] switch_channel.c:1123 New Channel sofia/external/+17702827403@gw4.telasip.com [649fd8d4-aa06-11ec-9c9e-6f21e8c76f10]
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:34:57.634389 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/+17702827403@gw4.telasip.com) Running State Change CS_NEW (Cur 1 Tot 21)
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:34:57.634389 99.33% [INFO] sofia.c:10462 sofia/external/+17702827403@gw4.telasip.com receiving invite from 18.215.9.144:5060 version: 1.10.7 -release 64bit call-id: 5e259a12-a1c0-41a9-a464-faac132ef511
2022-03-22 17:34:57.634389 99.33% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 18.215.9.144:0.
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:34:57.634389 99.33% [DEBUG] switch_core_state_machine.c:600 (sofia/external/+17702827403@gw4.telasip.com) State NEW
2022-03-22 17:34:57.634389 99.33% [DEBUG] sofia.c:2419 detaching session 649fd8d4-aa06-11ec-9c9e-6f21e8c76f10
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.654385 99.47% [WARNING] switch_core_state_machine.c:684 649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 sofia/external/+17702827403@gw4.telasip.com Abandoned
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.654385 99.47% [NOTICE] switch_core_state_machine.c:687 Hangup sofia/external/+17702827403@gw4.telasip.com [CS_NEW] [WRONG_CALL_STATE]
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.654385 99.47% [DEBUG] switch_core_state_machine.c:581 (sofia/external/+17702827403@gw4.telasip.com) Running State Change CS_HANGUP (Cur 1 Tot 21)
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.654385 99.47% [DEBUG] switch_core_state_machine.c:844 (sofia/external/+17702827403@gw4.telasip.com) Callstate Change DOWN -> HANGUP
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:846 (sofia/external/+17702827403@gw4.telasip.com) State HANGUP
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] mod_sofia.c:468 Channel sofia/external/+17702827403@gw4.telasip.com hanging up, cause: WRONG_CALL_STATE
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:59 sofia/external/+17702827403@gw4.telasip.com Standard HANGUP, cause: WRONG_CALL_STATE
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:846 (sofia/external/+17702827403@gw4.telasip.com) State HANGUP going to sleep
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:616 (sofia/external/+17702827403@gw4.telasip.com) State Change CS_HANGUP -> CS_REPORTING
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:581 (sofia/external/+17702827403@gw4.telasip.com) Running State Change CS_REPORTING (Cur 1 Tot 21)
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:932 (sofia/external/+17702827403@gw4.telasip.com) State REPORTING
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:168 sofia/external/+17702827403@gw4.telasip.com Standard REPORTING, cause: WRONG_CALL_STATE
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:932 (sofia/external/+17702827403@gw4.telasip.com) State REPORTING going to sleep
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:607 (sofia/external/+17702827403@gw4.telasip.com) State Change CS_REPORTING -> CS_DESTROY
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_session.c:1753 Session 21 (sofia/external/+17702827403@gw4.telasip.com) Locked, Waiting on external entities
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [NOTICE] switch_core_session.c:1771 Session 21 (sofia/external/+17702827403@gw4.telasip.com) Ended
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [NOTICE] switch_core_session.c:1775 Close Channel sofia/external/+17702827403@gw4.telasip.com [CS_DESTROY]
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:735 (sofia/external/+17702827403@gw4.telasip.com) Running State Change CS_DESTROY (Cur 0 Tot 21)
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:745 (sofia/external/+17702827403@gw4.telasip.com) State DESTROY
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] mod_sofia.c:379 sofia/external/+17702827403@gw4.telasip.com SOFIA DESTROY
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:175 sofia/external/+17702827403@gw4.telasip.com Standard DESTROY
649fd8d4-aa06-11ec-9c9e-6f21e8c76f10 2022-03-22 17:35:07.674354 99.47% [DEBUG] switch_core_state_machine.c:745 (sofia/external/+17702827403@gw4.telasip.com) State DESTROY going to sleep

System Information
Version    4.5.32
Git Information    Branch: master
Commit: 322eac8ac22534537db78412ac524c90e4667f73
Origin: https://github.com/fusionpbx/fusionpbx
Status: Your branch is up to date with 'origin/master'. +4 days ago
Project Path    /var/www/fusionpbx
Switch Version    1.10.7 (64bit)
PHP Version    7.4.3

 

[hfoster]

I've got a strong feeling from that WRONG_CALL_STATE that the SIP gateway IP is not in the domains ACL. I really can't remember the log lines if an ACL verifies or not.

Access Controls

 

[AverageGuy]

So I attempted to add an acl patterned after the rfc as:

.
Does that look right?
I reloadedacl from the Sip status menu and still get this:

2022-03-23 09:17:24.694384 99.27% [NOTICE] switch_channel.c:1123 New Channel sofia/external/+17702827403@gw4.telasip.com [0d4e0482-aa8a-11ec-9cdf-6f21e8c76f10]
2022-03-23 09:17:24.694384 99.27% [DEBUG] switch_core_state_machine.c:581 (sofia/external/+17702827403@gw4.telasip.com) Running State Change CS_NEW (Cur 1 Tot 28)
2022-03-23 09:17:24.694384 99.27% [INFO] sofia.c:10462 sofia/external/+17702827403@gw4.telasip.com receiving invite from 18.215.9.144:5060 version: 1.10.7 -release 64bit call-id: 1c80823f-5375-4b64-931c-cdbbab411e65
2022-03-23 09:17:24.694384 99.27% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 18.215.9.144:0.
2022-03-23 09:17:24.694384 99.27% [DEBUG] switch_core_state_machine.c:600 (sofia/external/+17702827403@gw4.telasip.com) State NEW
2022-03-23 09:17:24.694384 99.27% [DEBUG] sofia.c:2419 detaching session 0d4e0482-aa8a-11ec-9cdf-6f21e8c76f10
2022-03-23 09:17:34.734384 99.03% [WARNING] switch_core_state_machine.c:684 0d4e0482-aa8a-11ec-9cdf-6f21e8c76f10 sofia/external/+17702827403@gw4.telasip.com Abandoned
2022-03-23 09:17:34.734384 99.03% [NOTICE] switch_core_state_machine.c:687 Hangup sofia/external/+17702827403@gw4.telasip.com [CS_NEW] [WRONG_CALL_STATE]
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:581 (sofia/external/+17702827403@gw4.telasip.com) Running State Change CS_HANGUP (Cur 1 Tot 28)
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:844 (sofia/external/+17702827403@gw4.telasip.com) Callstate Change DOWN -> HANGUP
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:846 (sofia/external/+17702827403@gw4.telasip.com) State HANGUP
2022-03-23 09:17:34.734384 99.03% [DEBUG] mod_sofia.c:468 Channel sofia/external/+17702827403@gw4.telasip.com hanging up, cause: WRONG_CALL_STATE
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:59 sofia/external/+17702827403@gw4.telasip.com Standard HANGUP, cause: WRONG_CALL_STATE
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:846 (sofia/external/+17702827403@gw4.telasip.com) State HANGUP going to sleep
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:616 (sofia/external/+17702827403@gw4.telasip.com) State Change CS_HANGUP -> CS_REPORTING
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:581 (sofia/external/+17702827403@gw4.telasip.com) Running State Change CS_REPORTING (Cur 1 Tot 28)
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:932 (sofia/external/+17702827403@gw4.telasip.com) State REPORTING
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:168 sofia/external/+17702827403@gw4.telasip.com Standard REPORTING, cause: WRONG_CALL_STATE
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:932 (sofia/external/+17702827403@gw4.telasip.com) State REPORTING going to sleep
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_state_machine.c:607 (sofia/external/+17702827403@gw4.telasip.com) State Change CS_REPORTING -> CS_DESTROY
2022-03-23 09:17:34.734384 99.03% [DEBUG] switch_core_session.c:1753 Session 28 (sofia/external/+17702827403@gw4.telasip.com) Locked, Waiting on external entities
2022-03-23 09:17:34.734384 99.03% [NOTICE] switch_core_session.c:1771 Session 28 (sofia/external/+17702827403@gw4.telasip.com) Ended

Note I have two sip providers and it fails the same way for both of them.

Thanks,
Jim

 

[hfoster]

You'll have to modify the SIP Profiles if you change the name of the domains ACL that as the SIP profiles specifically reference the 'domains' ACL.

All you have to do is:

1. Click the domains ACL
2. Enter in on a new line an allow for the CIDR IP address range the calls are coming from, in this case it's your 18.215 address here.
3. Leave domains empty because we're not authorising by the domain
4. Put the description of the call provider in if you need to change it in the future.
5. Reload ACLs in the SIP Status section.

This essentially says to FreeSWITCH: 'If you see an INVITE from 12.34.56.78, don't try to auth it, and drop it in the public context for inbound routes.'

 

[AverageGuy]

Is that not what I did? Except I specified the IP address rather than a range of addresses. I did change it to 18.215/16 and it didn't make a difference. The instructions at the link you originally referenced suggests that the 18.215.9.144/32 is valid. Anyway it still doesn't work. I think the acl is OK

 

[hfoster]

I can see in the screenshot you renamed the 'domains' ACL to 'telesip'

 

[AverageGuy]

I didn't rename it, I added a new one.
Was I supposed to add to the existing domains? OK here's the edited domains entry: That finally worked.

Thanks a bunch.
Jim.

 

[hfoster]

Yeh, if you check out the SIP profiles you'll see they're referencing them by name.