Fresh install: DESTINATION_OUT_OF_ORDER on all inbound calls

Status
Not open for further replies.
I

IshavAulakh

New Member
Jul 14, 2021
14
1
3
30
India
Need help with a fresh install on Debian 11. Getting DESTINATION_OUT_OF_ORDER despite the extension being registered. The call also does not go further for action two on the destination.

System Information​
Version5.1.1
Git InformationBranch: master
Commit: 2ce9254ce5ad7dc4c1b39115c67263710973bdc4
Origin: https://github.com/fusionpbx/fusionpbx
Status: Your branch is up to date with 'origin/master'. +7 days ago
Project Path/var/www/fusionpbx/
Switch Version1.10.9 (64bit)
PHP Version8.1.23

Operating System Information​
Operating System Debian
Version 11
Kernel Linux pbx 5.10.0-25-amd64 #1 SMP Debian 5.10.191-1 (2023-08-16) x86_64 GNU/Linux
Uptime03:59:08 up 16 min, 1 user, load average: 0.08, 0.02, 0.01
DateThu, 14 Sep 2023 03:59:08 +0530
Sip log:

a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:581 (sofia/external/anonymous@51.38.226.132) Running State Change CS_NEW (Cur 1 Tot 3)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [INFO] sofia.c:10453 sofia/external/anonymous@51.38.226.132 receiving invite from 51.38.226.132:5060 version: 1.10.9 -release 64bit call-id: 9dc5317c-cd25-123c-44ae-fa163ec04d08
2023-09-14 03:43:30.960360 99.07% [DEBUG] sofia.c:10547 verifying acl "providers" for ip/port 51.38.226.132:0.
2023-09-14 03:43:30.960360 99.07% [DEBUG] sofia.c:10576 IP 51.38.226.132 Approved by acl "providers[]". Access Granted.
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] sofia.c:7487 Channel sofia/external/anonymous@51.38.226.132 entering state [received][100]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] sofia.c:7497 Remote SDP:
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb v=0
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb o=FreeSWITCH 1694624057 1694624058 IN IP4 51.38.226.132
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb s=FreeSWITCH
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb c=IN IP4 51.38.226.132
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb t=0 0
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb m=audio 19156 RTP/AVP 0 101
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=rtpmap:0 PCMU/8000
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=rtpmap:101 telephone-event/8000
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=fmtp:101 0-15
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=ptime:20
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] sofia.c:7900 (sofia/external/anonymous@51.38.226.132) State Change CS_NEW -> CS_INIT
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:600 (sofia/external/anonymous@51.38.226.132) State NEW
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:581 (sofia/external/anonymous@51.38.226.132) Running State Change CS_INIT (Cur 1 Tot 3)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:624 (sofia/external/anonymous@51.38.226.132) State INIT
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] mod_sofia.c:97 sofia/external/anonymous@51.38.226.132 SOFIA INIT
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:40 sofia/external/anonymous@51.38.226.132 Standard INIT
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:48 (sofia/external/anonymous@51.38.226.132) State Change CS_INIT -> CS_ROUTING
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:624 (sofia/external/anonymous@51.38.226.132) State INIT going to sleep
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:581 (sofia/external/anonymous@51.38.226.132) Running State Change CS_ROUTING (Cur 1 Tot 3)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_channel.c:2380 (sofia/external/anonymous@51.38.226.132) Callstate Change DOWN -> RINGING
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:640 (sofia/external/anonymous@51.38.226.132) State ROUTING
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] mod_sofia.c:158 sofia/external/anonymous@51.38.226.132 SOFIA ROUTING
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_core_state_machine.c:230 sofia/external/anonymous@51.38.226.132 Standard ROUTING
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [INFO] mod_dialplan_xml.c:639 Processing anonymous <anonymous>->+13802094244 in context public
2023-09-14 03:43:30.960360 99.07% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:public
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->caller-details] continue=true
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (PASS) [caller-details] () =~ // break=never
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action export(call_direction=inbound) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 export(call_direction=inbound)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.960360 99.07% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[inbound]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(caller_destination=${sip_to_user}) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(caller_destination=+13802094244)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [caller_destination]=[+13802094244]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(caller_id_name=${caller_id_name}) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(caller_id_name=anonymous)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [caller_id_name]=[anonymous]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(caller_id_number=${caller_id_number})
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->] continue=false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (FAIL) [] destination_number(+13802094244) =~ /^\+(13802094916)$/ break=on-false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->] continue=false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (FAIL) [] destination_number(+13802094244) =~ /^\+(13802091110)$/ break=on-false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->] continue=false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (FAIL) [] destination_number(+13802094244) =~ /^\+(13802131165)$/ break=on-false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->] continue=false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (FAIL) [] destination_number(+13802094244) =~ /^\+(13802091992)$/ break=on-false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->] continue=false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (FAIL) [] destination_number(+13802094244) =~ /^\+(13802131540)$/ break=on-false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->] continue=false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (FAIL) [] destination_number(+13802094244) =~ /^\+(13802094010)$/ break=on-false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 parsing [public->] continue=false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Regex (PASS) [] destination_number(+13802094244) =~ /^\+(13802094244)$/ break=on-false
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action export(call_direction=inbound) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 export(call_direction=inbound)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[inbound]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(domain_uuid=17dcc459-ae25-4684-98bc-39183f3f1b8b) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(domain_uuid=17dcc459-ae25-4684-98bc-39183f3f1b8b)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [domain_uuid]=[17dcc459-ae25-4684-98bc-39183f3f1b8b]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(domain_name=team1.globellc.us) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(domain_name=team1.globellc.us)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [domain_name]=[team1.globellc.us]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(record_path=${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(record_path=/var/lib/freeswitch/recordings/team1.globellc.us/archive/2023/Sep/14)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [record_path]=[/var/lib/freeswitch/recordings/team1.globellc.us/archive/2023/Sep/14]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(record_name=${uuid}.${record_ext}) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(record_name=a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb.wav)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [record_name]=[a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb.wav]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(record_append=true) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(record_append=true)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [record_append]=[true]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(record_in_progress=true) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(record_in_progress=true)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [record_in_progress]=[true]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action set(recording_follow_transfer=true) INLINE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(recording_follow_transfer=true)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [recording_follow_transfer]=[true]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action record_session(${record_path}/${record_name})
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb Dialplan: sofia/external/anonymous@51.38.226.132 Action ()
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_state_machine.c:281 (sofia/external/anonymous@51.38.226.132) State Change CS_ROUTING -> CS_EXECUTE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_state_machine.c:640 (sofia/external/anonymous@51.38.226.132) State ROUTING going to sleep
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_state_machine.c:581 (sofia/external/anonymous@51.38.226.132) Running State Change CS_EXECUTE (Cur 1 Tot 3)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_state_machine.c:647 (sofia/external/anonymous@51.38.226.132) State EXECUTE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_sofia.c:213 sofia/external/anonymous@51.38.226.132 SOFIA EXECUTE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_state_machine.c:323 sofia/external/anonymous@51.38.226.132 Standard EXECUTE
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 set(caller_id_number=anonymous)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_dptools.c:1673 SET sofia/external/anonymous@51.38.226.132 [caller_id_number]=[anonymous]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_session.c:2785 Application record_session Requires media! pre_answering channel sofia/external/anonymous@51.38.226.132
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [INFO] switch_core_session.c:2787 Sending early media
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:3750 Set Codec sofia/external/anonymous@51.38.226.132 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_codec.c:111 sofia/external/anonymous@51.38.226.132 Original read codec set to PCMU:0
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:5850 sofia/external/anonymous@51.38.226.132 Set 2833 dtmf send payload to 101 recv payload to 101
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/external/anonymous@51.38.226.132] 139.84.164.216 port 23266 -> 51.38.226.132 port 19156 codec: 0 ms: 20
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:8963 sofia/external/anonymous@51.38.226.132 Set 2833 dtmf send payload to 101
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:8970 sofia/external/anonymous@51.38.226.132 Set 2833 dtmf receive payload to 101
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:8993 sofia/external/anonymous@51.38.226.132 Set rtp dtmf delay to 40
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [NOTICE] sofia_media.c:90 Pre-Answer sofia/external/anonymous@51.38.226.132!
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_channel.c:3566 (sofia/external/anonymous@51.38.226.132) Callstate Change RINGING -> EARLY
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media.c:8633 Audio params are unchanged for sofia/external/anonymous@51.38.226.132.
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] mod_sofia.c:2609 Ring SDP:
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb v=0
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb o=FreeSWITCH 1694619944 1694619945 IN IP4 139.84.164.216
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb s=FreeSWITCH
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb c=IN IP4 139.84.164.216
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb t=0 0
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb m=audio 23266 RTP/AVP 0 101
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=rtpmap:0 PCMU/8000
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=rtpmap:101 telephone-event/8000
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=fmtp:101 0-15
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=silenceSupp:eek:ff - - - -
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=ptime:20
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb a=sendrecv
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb EXECUTE [depth=0] sofia/external/anonymous@51.38.226.132 record_session(/var/lib/freeswitch/recordings/team1.globellc.us/archive/2023/Sep/14/a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb.wav)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] sofia.c:7487 Channel sofia/external/anonymous@51.38.226.132 entering state [early][183]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media_bug.c:976 Attaching BUG to sofia/external/anonymous@51.38.226.132
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [ERR] switch_core_session.c:2760 Invalid Application
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [NOTICE] switch_core_session.c:2761 Hangup sofia/external/anonymous@51.38.226.132 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_state_machine.c:647 (sofia/external/anonymous@51.38.226.132) State EXECUTE going to sleep
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_state_machine.c:581 (sofia/external/anonymous@51.38.226.132) Running State Change CS_HANGUP (Cur 1 Tot 3)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_channel.c:3605 sofia/external/anonymous@51.38.226.132 skip receive message [VIDEO_SYNC] (channel is hungup already)
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_ivr_async.c:1596 Stop recording file /var/lib/freeswitch/recordings/team1.globellc.us/archive/2023/Sep/14/a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb.wav
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [DEBUG] switch_core_media_bug.c:1326 Removing BUG from sofia/external/anonymous@51.38.226.132
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,044
565
113
What is your destination entry for this look like?

The answer would appear to be here:

a1a63f08-cb55-4a47-8a3e-3eaa43db4cbb 2023-09-14 03:43:30.980362 99.07% [ERR] switch_core_session.c:2760 Invalid Application

It looks like it is trying to call an invalid application.
 
I

IshavAulakh

New Member
Jul 14, 2021
14
1
3
30
India
Issue is resolved now and cannot debug again as it is in production. As far as i can figure it out, importing extensions from csv is what caused the issue. The extensions as well as destinations were imported from version 4.8 to 5.1.1.

Finally got things working once i removed all destinations and extensions and recreated manually post a reboot.
 
Status
Not open for further replies.
Top Bottom