SOLVED New setup issues with inbound calls - outbound working

Status
Not open for further replies.

3d3dman

New Member
Oct 11, 2022
11
0
1
We are having issues with inbound calls - we are getting a 407 error (proxy authentication required)

We are using google cloud with all the correct firewall rules added

we have tried
1. Under gateway adding into the extension section auto_to_user
2. Adding my sip providers IP addresses into ACL through the CIDR section and not the domain section
3.Tried changing the presentation of the number without the international area code

From the logs I can see the number is hitting fusion then it is being rejected saying WRONG_CALL_STATE

Any ideas what the issue could be - see a log below - some of the info below like the phone number and IP have been changed for security


ia/external

entering state [calling][0]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] sofia.c:7502 Channel sofia/external/07766103858 entering state [proceeding][183]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [NOTICE] sofia.c:7515 Pre-Answer sofia/external/07766103858!
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_channel.c:3622 (sofia/external/07766103858) Callstate Change DOWN -> EARLY
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_media.c:3883 Set Codec sofia/external/07766103858 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_codec.c:111 sofia/external/07766103858 Original read codec set to PCMA:8
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_media.c:5986 sofia/external/07766103858 Set 2833 dtmf send payload to 101 recv payload to 101
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_media.c:8787 AUDIO RTP [sofia/external/07766103858] 10.154.0.4 port 32732 -> 180.91.41.40 port 18264 codec: 8 ms: 20
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_media.c:9099 sofia/external/07766103858 Set 2833 dtmf send payload to 101
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_media.c:9106 sofia/external/07766103858 Set 2833 dtmf receive payload to 101
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_media.c:9129 sofia/external/07766103858 Set rtp dtmf delay to 40
c4da7af9-b761-4d16-8794-5483af4be656 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/external/07766103858] Peer UUID: 7a0939ac-a329-4976-8905-ba88dc57419d
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_ivr_bridge.c:1791 (sofia/external/07766103858) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/external/07766103858) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 8)
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.221479 98.83% [DEBUG] switch_core_state_machine.c:650 (sofia/external/07766103858) State EXCHANGE_MEDIA
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.241448 98.83% [DEBUG] sofia.c:7502 Channel sofia/external/07766103858 entering state [proceeding][183]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.241448 98.83% [DEBUG] switch_core_media.c:5986 sofia/external/07766103858 Set 2833 dtmf send payload to 101 recv payload to 101
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.241448 98.83% [DEBUG] switch_core_media.c:8769 Audio params are unchanged for sofia/external/07766103858.
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.281478 98.83% [DEBUG] sofia.c:7502 Channel sofia/external/07766103858 entering state [proceeding][183]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.281478 98.83% [DEBUG] switch_core_media.c:5986 sofia/external/07766103858 Set 2833 dtmf send payload to 101 recv payload to 101
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:03.281478 98.83% [DEBUG] switch_core_media.c:8769 Audio params are unchanged for sofia/external/07766103858.
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:09.001503 98.80% [DEBUG] sofia.c:7502 Channel sofia/external/07766103858 entering state [completing][200]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:09.021530 98.80% [DEBUG] sofia.c:7502 Channel sofia/external/07766103858 entering state [ready][200]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:09.021530 98.80% [NOTICE] sofia.c:8631 Channel [sofia/external/07766103858] has been answered
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:09.021530 98.80% [DEBUG] switch_channel.c:3950 (sofia/external/07766103858) Callstate Change EARLY -> ACTIVE
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [NOTICE] sofia.c:1065 Hangup sofia/external/07766103858 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/external/07766103858]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:650 (sofia/external/07766103858) State EXCHANGE_MEDIA going to sleep
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/external/07766103858) Running State Change CS_HANGUP (Cur 2 Tot 8)
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:844 (sofia/external/07766103858) Callstate Change ACTIVE -> HANGUP
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:846 (sofia/external/07766103858) State HANGUP
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] mod_sofia.c:468 Channel sofia/external/07766103858 hanging up, cause: NORMAL_CLEARING
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:59 sofia/external/07766103858 Standard HANGUP, cause: NORMAL_CLEARING
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:846 (sofia/external/07766103858) State HANGUP going to sleep
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:616 (sofia/external/07766103858) State Change CS_HANGUP -> CS_REPORTING
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/external/07766103858) Running State Change CS_REPORTING (Cur 2 Tot 8)
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:932 (sofia/external/07766103858) State REPORTING
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:168 sofia/external/07766103858 Standard REPORTING, cause: NORMAL_CLEARING
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:932 (sofia/external/07766103858) State REPORTING going to sleep
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:607 (sofia/external/07766103858) State Change CS_REPORTING -> CS_DESTROY
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_session.c:1747 Session 8 (sofia/external/07766103858) Locked, Waiting on external entities
c4da7af9-b761-4d16-8794-5483af4be656 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_ivr_bridge.c:819 sofia/external/07766103858 ending bridge by request from write function
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [NOTICE] switch_core_session.c:1765 Session 8 (sofia/external/07766103858) Ended
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [NOTICE] switch_core_session.c:1769 Close Channel sofia/external/07766103858 [CS_DESTROY]
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:735 (sofia/external/07766103858) Running State Change CS_DESTROY (Cur 1 Tot 8)
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:745 (sofia/external/07766103858) State DESTROY
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] mod_sofia.c:379 sofia/external/07766103858 SOFIA DESTROY
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:175 sofia/external/07766103858 Standard DESTROY
7a0939ac-a329-4976-8905-ba88dc57419d 2023-02-01 12:13:26.741448 98.63% [DEBUG] switch_core_state_machine.c:745 (sofia/external/07766103858) State DESTROY going to sleep
 

3d3dman

New Member
Oct 11, 2022
11
0
1
Thanks for the reply, see below from the start to the end of the call

2023-02-03 09:25:41.081474 98.90% [DEBUG] sofia_reg.c:2650 Changing expire time to 3599 by request of proxy sip:185.91.41.30
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:31:59.281451 98.83% [NOTICE] switch_channel.c:1123 New Channel sofia/external/07766103850@185.91.41.30 [c446c032-d83d-4460-882c-1d6aac1f8efb]
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:31:59.281451 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/external/07766103850@185.91.41.30) Running State Change CS_NEW (Cur 1 Tot 41)
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:31:59.281451 98.83% [INFO] sofia.c:10468 sofia/external/07766103850@185.91.41.30 receiving invite from 185.91.41.30:5060 version: 1.10.8 -release 64bit call-id: e6385eba-43d3-4790-be51-46e00b6180a6
2023-02-03 09:31:59.281451 98.83% [DEBUG] sofia.c:10562 verifying acl "providers" for ip/port 185.91.41.30:0.
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:31:59.281451 98.83% [DEBUG] switch_core_state_machine.c:600 (sofia/external/07766103850@185.91.41.30) State NEW
2023-02-03 09:31:59.281451 98.83% [DEBUG] sofia.c:2419 detaching session c446c032-d83d-4460-882c-1d6aac1f8efb
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [WARNING] switch_core_state_machine.c:684 c446c032-d83d-4460-882c-1d6aac1f8efb sofia/external/07766103850@185.91.41.30 Abandoned
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [NOTICE] switch_core_state_machine.c:687 Hangup sofia/external/07766103850@185.91.41.30 [CS_NEW] [WRONG_CALL_STATE]
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/07766103850@185.91.41.30) Running State Change CS_HANGUP (Cur 1 Tot 41)
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:844 (sofia/external/07766103850@185.91.41.30) Callstate Change DOWN -> HANGUP
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:846 (sofia/external/07766103850@185.91.41.30) State HANGUP
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] mod_sofia.c:468 Channel sofia/external/07766103850@185.91.41.30 hanging up, cause: WRONG_CALL_STATE
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:59 sofia/external/07766103850@185.91.41.30 Standard HANGUP, cause: WRONG_CALL_STATE
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:846 (sofia/external/07766103850@185.91.41.30) State HANGUP going to sleep
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:616 (sofia/external/07766103850@185.91.41.30) State Change CS_HANGUP -> CS_REPORTING
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/07766103850@185.91.41.30) Running State Change CS_REPORTING (Cur 1 Tot 41)
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:932 (sofia/external/07766103850@185.91.41.30) State REPORTING
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:168 sofia/external/07766103850@185.91.41.30 Standard REPORTING, cause: WRONG_CALL_STATE
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:932 (sofia/external/07766103850@185.91.41.30) State REPORTING going to sleep
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:607 (sofia/external/07766103850@185.91.41.30) State Change CS_REPORTING -> CS_DESTROY
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_session.c:1747 Session 41 (sofia/external/07766103850@185.91.41.30) Locked, Waiting on external entities
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [NOTICE] switch_core_session.c:1765 Session 41 (sofia/external/07766103850@185.91.41.30) Ended
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [NOTICE] switch_core_session.c:1769 Close Channel sofia/external/07766103850@185.91.41.30 [CS_DESTROY]
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:735 (sofia/external/07766103850@185.91.41.30) Running State Change CS_DESTROY (Cur 0 Tot 41)
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:745 (sofia/external/07766103850@185.91.41.30) State DESTROY
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] mod_sofia.c:379 sofia/external/07766103850@185.91.41.30 SOFIA DESTROY
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:175 sofia/external/07766103850@185.91.41.30 Standard DESTROY
c446c032-d83d-4460-882c-1d6aac1f8efb 2023-02-03 09:32:09.321483 98.53% [DEBUG] switch_core_state_machine.c:745 (sofia/external/07766103850@185.91.41.30) State DESTROY going to sleep
 
Status
Not open for further replies.