Freeswitch auto respond 503 after sending 183 - Githubissues

signalwire / freeswitch

FreeSWITCH is a Software Defined Telecom Stack enabling the digital transformation from proprietary telecom switches to a versatile software implementation that runs on any commodity hardware. From a Raspberry PI to a multi-core server, FreeSWITCH can unlock the telecommunications potential of any device.

https://freeswitch.com/#getting-started

Other

3.32k stars 1.38k forks source link

Freeswitch auto respond 503 after sending 183 #2515

Open itpanda2024 opened 5 days ago

itpanda2024 commented 5 days ago

Describe the bug Diagram : customer --->kam proxy---> freeswitch---> provider

When we call out to provider , freeswitch receive 180 ring and respond 183 to kam proxy .when Customer is waiting answer, freeswitch respond 503 and end call

To Reproduce Steps to reproduce the behavior:

Using this example configuration...
Dial into conference using verto
Play my_problem_file.mp4 into conference
FreeSWITCH crashes

Expected behavior A clear and concise description of what you expected to happen.

Package version or git hash

FreeSWITCH Version 1.10.11

Trace logs [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Regex (PASS) [external_to_external] network_addr(125.212.210.235) =~ /^125.212.210.235$/ break=on-false [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Regex (PASS) [external_to_external] destination_number(88880985737311) =~ /^8888(\d+)$/ break=on-false [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Action set(call_timeout=100) [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Action set(continue_on_fail=true) [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Action set(hangup_after_bridge=true) [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Action set(dtmf_type=rfc2833) [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Action set(ignore_display_updates=true) [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Action set(export_vars=${sip_call_id}) [mfreeswitch@FS01> [K[33mDialplan: sofia/public_profile_ms01/0888888888@125.212.210.235 Action bridge({sip_invite_call_id=${sip_call_id}}sofia/gateway/public_sip_gw01/0985737311) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:281 (sofia/public_profile_ms01/0888888888@125.212.210.235) State Change CS_ROUTING -> CS_EXECUTE [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:640 (sofia/public_profile_ms01/0888888888@125.212.210.235) State ROUTING going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0888888888@125.212.210.235) Running State Change CS_EXECUTE (Cur 7 Tot 343588) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:647 (sofia/public_profile_ms01/0888888888@125.212.210.235) State EXECUTE [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_sofia.c:213 sofia/public_profile_ms01/0888888888@125.212.210.235 SOFIA EXECUTE [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:323 sofia/public_profile_ms01/0888888888@125.212.210.235 Standard EXECUTE [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 export(RFC2822_DATE=Tue, 02 Jul 2024 11:56:49 +0700) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [RFC2822_DATE]=[Tue, 02 Jul 2024 11:56:49 +0700] [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 set(call_timeout=100) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_dptools.c:1671 SET sofia/public_profile_ms01/0888888888@125.212.210.235 [call_timeout]=[100] [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 set(continue_on_fail=true) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_dptools.c:1671 SET sofia/public_profile_ms01/0888888888@125.212.210.235 [continue_on_fail]=[true] [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 set(hangup_after_bridge=true) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_dptools.c:1671 SET sofia/public_profile_ms01/0888888888@125.212.210.235 [hangup_after_bridge]=[true] [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 set(dtmf_type=rfc2833) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_dptools.c:1671 SET sofia/public_profile_ms01/0888888888@125.212.210.235 [dtmf_type]=[rfc2833] [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 set(ignore_display_updates=true) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_dptools.c:1671 SET sofia/public_profile_ms01/0888888888@125.212.210.235 [ignore_display_updates]=[true] [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 set(export_vars=675f062305b7bc471e12102823539445@203.162.79.243:5060) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_dptools.c:1671 SET sofia/public_profile_ms01/0888888888@125.212.210.235 [export_vars]=[675f062305b7bc471e12102823539445@203.162.79.243:5060] [mfreeswitch@FS01> [K[32mEXECUTE [depth=0] sofia/public_profile_ms01/0888888888@125.212.210.235 bridge({sip_invite_call_id=675f062305b7bc471e12102823539445@203.162.79.243:5060}sofia/gateway/public_sip_gw01/0985737311) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_ivr_originate.c:2301 Parsing global variables [mfreeswitch@FS01> [K[36m2024-07-02 11:56:49.855188 98.83% [NOTICE] switch_channel.c:1142 New Channel sofia/public_profile_ms01/0985737311 [12abea74-934a-4e53-8f60-5d45fb46ddb2] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_sofia.c:5110 (sofia/public_profile_ms01/0985737311) State Change CS_NEW -> CS_INIT [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0985737311) Running State Change CS_INIT (Cur 8 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:624 (sofia/public_profile_ms01/0985737311) State INIT [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_sofia.c:97 sofia/public_profile_ms01/0985737311 SOFIA INIT [mfreeswitch@FS01> [K[32m2024-07-02 11:56:49.855188 98.83% [INFO] sofia_glue.c:1659 sofia/public_profile_ms01/0985737311 sending invite call-id: 675f062305b7bc471e12102823539445@203.162.79.243:5060 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] sofia_glue.c:1662 sofia/public_profile_ms01/0985737311 sending invite version: 1.10.11-release git f24064f 2023-12-22 18:08:31Z 64bit Local SDP: v=0 o=FreeSWITCH 1719878331 1719878332 IN IP4 125.212.210.236 s=FreeSWITCH c=IN IP4 125.212.210.236 t=0 0 m=audio 17878 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv

[mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:40 sofia/public_profile_ms01/0985737311 Standard INIT [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:48 (sofia/public_profile_ms01/0985737311) State Change CS_INIT -> CS_ROUTING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:624 (sofia/public_profile_ms01/0985737311) State INIT going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0985737311) Running State Change CS_ROUTING (Cur 8 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:640 (sofia/public_profile_ms01/0985737311) State ROUTING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] mod_sofia.c:158 sofia/public_profile_ms01/0985737311 SOFIA ROUTING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_ivr_originate.c:67 (sofia/public_profile_ms01/0985737311) State Change CS_ROUTING -> CS_CONSUME_MEDIA [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:640 (sofia/public_profile_ms01/0985737311) State ROUTING going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0985737311) Running State Change CS_CONSUME_MEDIA (Cur 8 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] sofia.c:7493 Channel sofia/public_profile_ms01/0985737311 entering state [calling][0] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:659 (sofia/public_profile_ms01/0985737311) State CONSUME_MEDIA [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.855188 98.83% [DEBUG] switch_core_state_machine.c:659 (sofia/public_profile_ms01/0985737311) State CONSUME_MEDIA going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.895191 98.83% [DEBUG] sofia.c:7493 Channel sofia/public_profile_ms01/0985737311 entering state [proceeding][180] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=callstack 510367178 370615647 IN IP4 103.234.161.6 s=session c=IN IP4 103.234.161.6 t=0 0 m=audio 26340 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15

[mfreeswitch@FS01> [K[36m2024-07-02 11:56:49.915157 98.83% [NOTICE] sofia.c:7506 Pre-Answer sofia/public_profile_ms01/0985737311! [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_channel.c:3585 (sofia/public_profile_ms01/0985737311) Callstate Change DOWN -> EARLY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:3731 Set Codec sofia/public_profile_ms01/0985737311 PCMU/8000 20 ms 160 samples 64000 bits 1 channels [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_codec.c:111 sofia/public_profile_ms01/0985737311 Original read codec set to PCMU:0 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5856 sofia/public_profile_ms01/0985737311 Set 2833 dtmf send payload to 101 recv payload to 101 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:8660 AUDIO RTP [sofia/public_profile_ms01/0985737311] 125.212.210.236 port 17878 -> 103.234.161.6 port 26340 codec: 0 ms: 20 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_rtp.c:4563 Starting timer [soft] 160 bytes per 20ms [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:8973 sofia/public_profile_ms01/0985737311 Set 2833 dtmf send payload to 101 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:8980 sofia/public_profile_ms01/0985737311 Set 2833 dtmf receive payload to 101 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:9003 sofia/public_profile_ms01/0985737311 Set rtp dtmf delay to 40 [mfreeswitch@FS01> [K[32m2024-07-02 11:56:49.915157 98.83% [INFO] switch_ivr_originate.c:3862 Sending early media [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:3731 Set Codec sofia/public_profile_ms01/0888888888@125.212.210.235 PCMU/8000 20 ms 160 samples 64000 bits 1 channels [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_codec.c:111 sofia/public_profile_ms01/0888888888@125.212.210.235 Original read codec set to PCMU:0 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:5856 sofia/public_profile_ms01/0888888888@125.212.210.235 Set 2833 dtmf send payload to 101 recv payload to 101 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:8660 AUDIO RTP [sofia/public_profile_ms01/0888888888@125.212.210.235] 125.212.210.236 port 25422 -> 203.162.79.243 port 10880 codec: 0 ms: 20 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_rtp.c:4563 Starting timer [soft] 160 bytes per 20ms [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:8973 sofia/public_profile_ms01/0888888888@125.212.210.235 Set 2833 dtmf send payload to 101 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:8980 sofia/public_profile_ms01/0888888888@125.212.210.235 Set 2833 dtmf receive payload to 101 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:9003 sofia/public_profile_ms01/0888888888@125.212.210.235 Set rtp dtmf delay to 40 [mfreeswitch@FS01> [K[36m2024-07-02 11:56:49.915157 98.83% [NOTICE] sofia_media.c:90 Pre-Answer sofia/public_profile_ms01/0888888888@125.212.210.235! [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_channel.c:3585 (sofia/public_profile_ms01/0888888888@125.212.210.235) Callstate Change RINGING -> EARLY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/public_profile_ms01/0888888888@125.212.210.235. [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] mod_sofia.c:2607 Ring SDP: v=0 o=FreeSWITCH 1719870787 1719870788 IN IP4 125.212.210.236 s=FreeSWITCH c=IN IP4 125.212.210.236 t=0 0 m=audio 25422 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv

[mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_ivr_originate.c:3913 Originate Resulted in Success: [sofia/public_profile_ms01/0985737311] Peer UUID: 12abea74-934a-4e53-8f60-5d45fb46ddb2 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] sofia.c:7493 Channel sofia/public_profile_ms01/0888888888@125.212.210.235 entering state [early][183] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_ivr_bridge.c:1791 (sofia/public_profile_ms01/0985737311) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0985737311) Running State Change CS_EXCHANGE_MEDIA (Cur 8 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] switch_core_state_machine.c:650 (sofia/public_profile_ms01/0985737311) State EXCHANGE_MEDIA [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.915157 98.83% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.975187 98.83% [DEBUG] switch_rtp.c:7695 Correct audio ip/port confirmed. [mfreeswitch@FS01> [K[33m2024-07-02 11:56:49.995165 98.83% [DEBUG] switch_rtp.c:7695 Correct audio ip/port confirmed. [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] sofia.c:7493 Channel sofia/public_profile_ms01/0985737311 entering state [terminated][503] [mfreeswitch@FS01> [K[36m2024-07-02 11:56:57.755143 98.67% [NOTICE] sofia.c:8735 Hangup sofia/public_profile_ms01/0985737311 [CS_EXCHANGE_MEDIA] [NORMAL_TEMPORARY_FAILURE] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_ivr_bridge.c:825 sofia/public_profile_ms01/0985737311 ending bridge by request from read function [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/public_profile_ms01/0985737311] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:650 (sofia/public_profile_ms01/0985737311) State EXCHANGE_MEDIA going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0985737311) Running State Change CS_HANGUP (Cur 8 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:844 (sofia/public_profile_ms01/0985737311) Callstate Change EARLY -> HANGUP [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:846 (sofia/public_profile_ms01/0985737311) State HANGUP [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] mod_sofia.c:469 Channel sofia/public_profile_ms01/0985737311 hanging up, cause: NORMAL_TEMPORARY_FAILURE [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:59 sofia/public_profile_ms01/0985737311 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:846 (sofia/public_profile_ms01/0985737311) State HANGUP going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:616 (sofia/public_profile_ms01/0985737311) State Change CS_HANGUP -> CS_REPORTING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0985737311) Running State Change CS_REPORTING (Cur 8 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:932 (sofia/public_profile_ms01/0985737311) State REPORTING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:168 sofia/public_profile_ms01/0985737311 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:932 (sofia/public_profile_ms01/0985737311) State REPORTING going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:607 (sofia/public_profile_ms01/0985737311) State Change CS_REPORTING -> CS_DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_session.c:1744 Session 343589 (sofia/public_profile_ms01/0985737311) Locked, Waiting on external entities [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_ivr_bridge.c:819 sofia/public_profile_ms01/0985737311 ending bridge by request from write function [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/public_profile_ms01/0888888888@125.212.210.235] [mfreeswitch@FS01> [K[36m2024-07-02 11:56:57.755143 98.67% [NOTICE] switch_core_session.c:1762 Session 343589 (sofia/public_profile_ms01/0985737311) Ended [mfreeswitch@FS01> [K[36m2024-07-02 11:56:57.755143 98.67% [NOTICE] switch_core_session.c:1766 Close Channel sofia/public_profile_ms01/0985737311 [CS_DESTROY] [mfreeswitch@FS01> [K[36m2024-07-02 11:56:57.755143 98.67% [NOTICE] switch_core_state_machine.c:382 sofia/public_profile_ms01/0888888888@125.212.210.235 has executed the last dialplan instruction, hanging up. [mfreeswitch@FS01> [K[36m2024-07-02 11:56:57.755143 98.67% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/public_profile_ms01/0888888888@125.212.210.235 [CS_EXECUTE] [NORMAL_CLEARING] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:735 (sofia/public_profile_ms01/0985737311) Running State Change CS_DESTROY (Cur 7 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:745 (sofia/public_profile_ms01/0985737311) State DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] mod_sofia.c:380 sofia/public_profile_ms01/0985737311 SOFIA DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:647 (sofia/public_profile_ms01/0888888888@125.212.210.235) State EXECUTE going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0888888888@125.212.210.235) Running State Change CS_HANGUP (Cur 7 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:175 sofia/public_profile_ms01/0985737311 Standard DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:745 (sofia/public_profile_ms01/0985737311) State DESTROY going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:844 (sofia/public_profile_ms01/0888888888@125.212.210.235) Callstate Change EARLY -> HANGUP [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:846 (sofia/public_profile_ms01/0888888888@125.212.210.235) State HANGUP [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] mod_sofia.c:463 sofia/public_profile_ms01/0888888888@125.212.210.235 Overriding SIP cause 480 with 503 from the other leg [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] mod_sofia.c:469 Channel sofia/public_profile_ms01/0888888888@125.212.210.235 hanging up, cause: NORMAL_CLEARING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 503 [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:59 sofia/public_profile_ms01/0888888888@125.212.210.235 Standard HANGUP, cause: NORMAL_CLEARING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:846 (sofia/public_profile_ms01/0888888888@125.212.210.235) State HANGUP going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:616 (sofia/public_profile_ms01/0888888888@125.212.210.235) State Change CS_HANGUP -> CS_REPORTING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:581 (sofia/public_profile_ms01/0888888888@125.212.210.235) Running State Change CS_REPORTING (Cur 7 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:932 (sofia/public_profile_ms01/0888888888@125.212.210.235) State REPORTING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:168 sofia/public_profile_ms01/0888888888@125.212.210.235 Standard REPORTING, cause: NORMAL_CLEARING [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:932 (sofia/public_profile_ms01/0888888888@125.212.210.235) State REPORTING going to sleep [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:607 (sofia/public_profile_ms01/0888888888@125.212.210.235) State Change CS_REPORTING -> CS_DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_session.c:1744 Session 343588 (sofia/public_profile_ms01/0888888888@125.212.210.235) Locked, Waiting on external entities [mfreeswitch@FS01> [K[36m2024-07-02 11:56:57.755143 98.67% [NOTICE] switch_core_session.c:1762 Session 343588 (sofia/public_profile_ms01/0888888888@125.212.210.235) Ended [mfreeswitch@FS01> [K[36m2024-07-02 11:56:57.755143 98.67% [NOTICE] switch_core_session.c:1766 Close Channel sofia/public_profile_ms01/0888888888@125.212.210.235 [CS_DESTROY] [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:735 (sofia/public_profile_ms01/0888888888@125.212.210.235) Running State Change CS_DESTROY (Cur 6 Tot 343589) [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:745 (sofia/public_profile_ms01/0888888888@125.212.210.235) State DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] mod_sofia.c:380 sofia/public_profile_ms01/0888888888@125.212.210.235 SOFIA DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:175 sofia/public_profile_ms01/0888888888@125.212.210.235 Standard DESTROY [mfreeswitch@FS01> [K[33m2024-07-02 11:56:57.755143 98.67% [DEBUG] switch_core_state_machine.c:745 (sofia/public_profile_ms01/0888888888@125.212.210.235) State DESTROY going to sleep

suiyuemanbu commented 1 day ago

provider return 503, check the provider server.