Water flow at Beach and Seagull Noises randomly when calling out

nocstaff · Oct 22, 2024

Hello. I have 2 different Fusion PBX servers in 2 different locations. We are having a weird issue randomly on both systems. The best way I can explain it is sometimes when we call outbound through one of our Yealink (Fusion PBX) phones, we hear dead silence then after a couple seconds we hear beach and Seagull recording noises. The call never goes through, and we cannot hear the person we are trying to call.

When looking at the CDR for the call, we get a normal_clearing for hangup cause and the status shows answered. But we cannot here the person on the other side. We have multiple domains on one of the Fusion PBX servers and it is only happening one of the domains. The only thing that is different from the other domains is that we have Call recording enabled. Then the other server that is experiencing the same issue also has call recording enabled.

Is anyone else experiencing this issue??

It doesn't look like I can attach the call recording to this forum but imagine water flowing at a beach and bird seagull noises is what it sounds like.

Let me know if anyone else is having the same issue.

pbxgeek · Oct 23, 2024

Can you show us how you enabled the call recording?

nocstaff · Oct 23, 2024

I just went into each extension in the domain and under record I selected all.

pbxgeek · Oct 23, 2024

ok can you show us a pcap of the call that has an issue?

nocstaff · Oct 25, 2024

I compared a bad call with a normal call to the same number.
**********************------------------------------------------------------------************************** is where the differences between the 2 calls started.

Let me know if this is what you were looking for.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.709966 99.97% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/4707@fusionpbx.example.com [59aea0d8-c8e4-4ed5-822e-a49c515ea6ea]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.709966 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4707@fusionpbx.example.com) Running State Change CS_NEW (Cur 1 Tot 4979)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.709966 99.97% [INFO] sofia.c:10459 sofia/internal/4707@fusionpbx.example.com receiving invite from 192.168.1.106:41994 version: 1.10.10 -release 64bit call-id: f382951a679b14d6a843ccdf24098331

2024-10-23 10:03:35.709966 99.97% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 192.168.1.106:0.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.709966 99.97% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/4707@fusionpbx.example.com) State NEW

2024-10-23 10:03:35.729930 99.97% [DEBUG] sofia.c:2419 detaching session 59aea0d8-c8e4-4ed5-822e-a49c515ea6ea

2024-10-23 10:03:35.729930 99.97% [DEBUG] sofia.c:2532 Re-attaching to session 59aea0d8-c8e4-4ed5-822e-a49c515ea6ea

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.729930 99.97% [INFO] sofia.c:10459 sofia/internal/4707@fusionpbx.example.com receiving invite from 192.168.1.106:41994 version: 1.10.10 -release 64bit call-id: f382951a679b14d6a843ccdf24098331

2024-10-23 10:03:35.749939 99.97% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 192.168.1.106:0.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] sofia.c:7493 Channel sofia/internal/4707@fusionpbx.example.com entering state [received][100]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] sofia.c:7503 Remote SDP:

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea v=0

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea o=- 12345678910 12345678910 IN IP4 192.168.1.106

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea s=Polycom IP Phone

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea c=IN IP4 192.168.1.106

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea b=AS:2048

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea t=0 0

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=sendrecv

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea m=audio 2306 RTP/AVP 115 99 9 102 0 8 18 127

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:115 G7221/32000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:115 bitrate=48000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:99 SIREN14/16000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:99 bitrate=48000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:9 G722/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:102 G7221/16000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:102 bitrate=32000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:0 PCMU/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:8 PCMA/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:18 G729/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:18 annexb=no

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:127 telephone-event/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea m=video 2308 RTP/AVP 109 34

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:109 H264/90000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:109 profile-level-id=42800d; packetization-mode=0

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:34 H263/90000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:34 CIF=1;QCIF=1;SQCIF=1

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] sofia.c:7906 (sofia/internal/4707@fusionpbx.example.com) State Change CS_NEW -> CS_INIT

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4707@fusionpbx.example.com) Running State Change CS_INIT (Cur 1 Tot 4979)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/4707@fusionpbx.example.com) State INIT

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] mod_sofia.c:97 sofia/internal/4707@fusionpbx.example.com SOFIA INIT

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:40 sofia/internal/4707@fusionpbx.example.com Standard INIT

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/4707@fusionpbx.example.com) State Change CS_INIT -> CS_ROUTING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/4707@fusionpbx.example.com) State INIT going to sleep

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4707@fusionpbx.example.com) Running State Change CS_ROUTING (Cur 1 Tot 4979)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_channel.c:2399 (sofia/internal/4707@fusionpbx.example.com) Callstate Change DOWN -> RINGING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/4707@fusionpbx.example.com) State ROUTING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] mod_sofia.c:158 sofia/internal/4707@fusionpbx.example.com SOFIA ROUTING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [DEBUG] switch_core_state_machine.c:230 sofia/internal/4707@fusionpbx.example.com Standard ROUTING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.749939 99.97% [INFO] mod_dialplan_xml.c:639 Processing Research <4707>->12345678910 in context fusionpbx.example.com

2024-10-23 10:03:35.749939 99.97% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:fusionpbx.example.com

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->user_exists] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_exists] () =~ // break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(user_exists=false)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [user_exists]=[false]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(from_user_exists=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [from_user_exists]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->caller-details] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(caller_destination=${destination_number}) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(caller_destination=12345678910)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [caller_destination]=[12345678910]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(caller_id_name=${caller_id_name}) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(caller_id_name=4707)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [caller_id_name]=[4707]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(caller_id_number=${caller_id_number})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->global-variables] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [global-variables] () =~ // break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(timezone=America/Anchorage) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(timezone=America/Anchorage)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [timezone]=[America/Anchorage]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->domain-variables] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [domain-variables] () =~ // break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action export(origination_callee_id_name=${caller_destination})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(operator=1000) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(operator=1000)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [operator]=[1000]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->clear_sip_auto_answer] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->is_loopback] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->call-direction] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [call-direction] ${call_direction}() =~ /^$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action export(call_direction=local) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com export(call_direction=local)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[local]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->user_record] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${user_record}(all) =~ /^all$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(record_session=true) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(record_session=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [record_session]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^inbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^outbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^local$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(from_user_record=all)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [from_user_record]=[all]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${from_user_record}(all) =~ /^all$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(record_session=true) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(record_session=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [record_session]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^inbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^outbound$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^local$/ break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [user_record] ${record_session}(true) =~ /^true$/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(record_path=${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(record_path=/var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [record_path]=[/var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(record_name=${uuid}.${record_ext}) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(record_name=59aea0d8-c8e4-4ed5-822e-a49c515ea6ea.wav)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [record_name]=[59aea0d8-c8e4-4ed5-822e-a49c515ea6ea.wav]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action mkdir(${record_path})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(recording_follow_transfer=true) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(recording_follow_transfer=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [recording_follow_transfer]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action bind_digit_action(local,*5,api:uuid_record,${uuid} mask ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},both,self)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action bind_digit_action(local,*6,api:uuid_record,${uuid} unmask ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},both,self)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(record_append=true) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(record_append=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [record_append]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(record_in_progress=true) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(record_in_progress=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [record_in_progress]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(RECORD_ANSWER_REQ=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action record_session(${record_path}/${record_name})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->rtp_has_crypto] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [rtp_has_crypto] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->redial] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [redial] destination_number(12345678910) =~ /^(redial|\*870)$/ break=on-true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [redial] () =~ // break=never

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->speed_dial] continue=false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [speed_dial] destination_number(12345678910) =~ /^\*0(.*)$/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->user_hold_music] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->default_caller_id] continue=true

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com parsing [fusionpbx.example.com->sip.example.net.9.11d] continue=false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [sip.example.net.9.11d] ${user_exists}(false) =~ /false/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Regex (PASS) [sip.example.net.9.11d] destination_number(12345678910) =~ /^\+?(\d{11})$/ break=on-false

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action export(call_direction=outbound) INLINE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com export(call_direction=outbound)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[outbound]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action unset(call_timeout)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(hangup_after_bridge=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(effective_caller_id_name=${outbound_caller_id_name})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(effective_caller_id_number=${outbound_caller_id_number})

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(inherit_codec=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(ignore_display_updates=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(callee_id_number=12345678910)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action set(outbound_prefix=9)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea Dialplan: sofia/internal/4707@fusionpbx.example.com Action bridge(sofia/gateway/b9083346-cd00-4461-902f-acf304b956d2/12345678910)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/4707@fusionpbx.example.com) State Change CS_ROUTING -> CS_EXECUTE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/4707@fusionpbx.example.com) State ROUTING going to sleep

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4707@fusionpbx.example.com) Running State Change CS_EXECUTE (Cur 1 Tot 4979)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/4707@fusionpbx.example.com) State EXECUTE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_sofia.c:213 sofia/internal/4707@fusionpbx.example.com SOFIA EXECUTE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_state_machine.c:323 sofia/internal/4707@fusionpbx.example.com Standard EXECUTE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(caller_id_number=4707)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [caller_id_number]=[4707]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(RFC2822_DATE=Wed, 23 Oct 2024 10:03:35 -0800)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [RFC2822_DATE]=[Wed, 23 Oct 2024 10:03:35 -0800]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com export(origination_callee_id_name=12345678910)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [origination_callee_id_name]=[12345678910]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com mkdir(/var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:664 sofia/internal/4707@fusionpbx.example.com MKDIR: /var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com bind_digit_action(local,*5,api:uuid_record,59aea0d8-c8e4-4ed5-822e-a49c515ea6ea mask /var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23/59aea0d8-c8e4-4ed5-822e-a49c515ea6ea.wav,both,self)

2024-10-23 10:03:35.769928 99.97% [INFO] switch_ivr_async.c:221 Digit parser DPTOOLS: Setting realm to 'local'

2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *5/local/0 callback: 0x7f8c32bca830 data: 0x7f8b6c02ed28

2024-10-23 10:03:35.769928 99.97% [INFO] switch_ivr_async.c:221 Digit parser DPTOOLS: Setting realm to 'local'

2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *5/local/0 callback: 0x7f8c32bca830 data: 0x7f8b6c02ee28

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com bind_digit_action(local,*6,api:uuid_record,59aea0d8-c8e4-4ed5-822e-a49c515ea6ea unmask /var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23/59aea0d8-c8e4-4ed5-822e-a49c515ea6ea.wav,both,self)

2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *6/local/0 callback: 0x7f8c32bca830 data: 0x7f8b6c02f180

2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *6/local/0 callback: 0x7f8c32bca830 data: 0x7f8b6c02f280

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(RECORD_ANSWER_REQ=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [RECORD_ANSWER_REQ]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_session.c:2791 Application record_session Requires media! pre_answering channel sofia/internal/4707@fusionpbx.example.com

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [INFO] switch_core_session.c:2793 Sending early media

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:115:32000:20:0:1]/[G722:9:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMU:0:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMA:8:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[G722:9:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMU:0:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMA:8:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:102:16000:20:0:1]/[G722:9:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMA:8:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 127@8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:3734 Set Codec sofia/internal/4707@fusionpbx.example.com G722/8000 20 ms 160 samples 64000 bits 1 channels

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_codec.c:111 sofia/internal/4707@fusionpbx.example.com Original read codec set to G722:9

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 127@8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:5856 sofia/internal/4707@fusionpbx.example.com Set 2833 dtmf send payload to 127 recv payload to 127

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:6192 No matches with FTMP, fallback to ignoring FMTP

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:6200 No matches with inherit_codec, fallback to ignoring PT

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/internal/4707@fusionpbx.example.com] 192.168.1.7 port 18362 -> 192.168.1.106 port 2306 codec: 9 ms: 20

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:8972 sofia/internal/4707@fusionpbx.example.com Set 2833 dtmf send payload to 127

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:8979 sofia/internal/4707@fusionpbx.example.com Set 2833 dtmf receive payload to 127

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:9002 sofia/internal/4707@fusionpbx.example.com Set rtp dtmf delay to 40

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [NOTICE] sofia_media.c:90 Pre-Answer sofia/internal/4707@fusionpbx.example.com!

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_channel.c:3585 (sofia/internal/4707@fusionpbx.example.com) Callstate Change RINGING -> EARLY

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/internal/4707@fusionpbx.example.com.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] mod_sofia.c:2607 Ring SDP:

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea v=0

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea o=FreeSWITCH 1729688253 1729688254 IN IP4 192.168.1.7

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea s=FreeSWITCH

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea c=IN IP4 192.168.1.7

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea t=0 0

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea m=audio 18362 RTP/AVP 9 127

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:9 G722/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:127 telephone-event/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:127 0-15

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=ptime:20

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=sendrecv

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea m=video 0 RTP/AVP 19

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com record_session(/var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23/59aea0d8-c8e4-4ed5-822e-a49c515ea6ea.wav)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.769928 99.97% [DEBUG] sofia.c:7493 Channel sofia/internal/4707@fusionpbx.example.com entering state [early][183]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_ivr_async.c:1504 Record session sample rate: 16000 -> 16000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_media_bug.c:976 Attaching BUG to sofia/internal/4707@fusionpbx.example.com

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com hash(insert/fusionpbx.example.com-last_dial/4707/12345678910)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com unset(call_timeout)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(hangup_after_bridge=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [hangup_after_bridge]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(effective_caller_id_name=Urban Pain)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [effective_caller_id_name]=[Urban Pain]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(effective_caller_id_number=9072777246)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [effective_caller_id_number]=[9072777246]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(inherit_codec=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [inherit_codec]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(ignore_display_updates=true)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [ignore_display_updates]=[true]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(callee_id_number=12345678910)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [callee_id_number]=[12345678910]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com set(outbound_prefix=9)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4707@fusionpbx.example.com [outbound_prefix]=[9]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea EXECUTE [depth=0] sofia/internal/4707@fusionpbx.example.com bridge(sofia/gateway/b9083346-cd00-4461-902f-acf304b956d2/12345678910)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_channel.c:1288 sofia/internal/4707@fusionpbx.example.com EXPORTING[export_vars] [domain_name]=[fusionpbx.example.com] to event

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_channel.c:1288 sofia/internal/4707@fusionpbx.example.com EXPORTING[export_vars] [domain_uuid]=[1acc08e3-df23-4d9b-bd2f-7b75da782a62] to event

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_channel.c:1288 sofia/internal/4707@fusionpbx.example.com EXPORTING[export_vars] [call_direction]=[outbound] to event

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_channel.c:1288 sofia/internal/4707@fusionpbx.example.com EXPORTING[export_vars] [call_direction]=[outbound] to event

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_channel.c:1288 sofia/internal/4707@fusionpbx.example.com EXPORTING[export_vars] [origination_callee_id_name]=[12345678910] to event

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 `.790020 99.97% [NOTICE] switch_channel.c:1142 New Channel sofia/external/12345678910 [93d9cb4b-4db0-43b8-8659-64c9afbdb46a]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_sofia.c:5110 (sofia/external/12345678910) State Change CS_NEW -> CS_INIT

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/12345678910) Running State Change CS_INIT (Cur 2 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/external/12345678910) State INIT

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_sofia.c:97 sofia/external/12345678910 SOFIA INIT

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [INFO] sofia_glue.c:1659 sofia/external/12345678910 sending invite call-id: (null)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] sofia_glue.c:1662 sofia/external/12345678910 sending invite version: 1.10.10 -release 64bit

93d9cb4b-4db0-43b8-8659-64c9afbdb46a Local SDP:

93d9cb4b-4db0-43b8-8659-64c9afbdb46a v=0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a o=FreeSWITCH 1729685955 1729685956 IN IP4 xxx.xxx.xxx.xxx

93d9cb4b-4db0-43b8-8659-64c9afbdb46a s=FreeSWITCH

93d9cb4b-4db0-43b8-8659-64c9afbdb46a c=IN IP4 xxx.xxx.xxx.xxx

93d9cb4b-4db0-43b8-8659-64c9afbdb46a t=0 0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a m=audio 20660 RTP/AVP 9 0 8 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:9 G722/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:0 PCMU/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:8 PCMA/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:101 telephone-event/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=fmtp:101 0-15

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=silenceSupp

ff - - - -

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=ptime:20

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=sendrecv

93d9cb4b-4db0-43b8-8659-64c9afbdb46a

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:40 sofia/external/12345678910 Standard INIT

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:48 (sofia/external/12345678910) State Change CS_INIT -> CS_ROUTING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/external/12345678910) State INIT going to sleep

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/12345678910) Running State Change CS_ROUTING (Cur 2 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/12345678910) State ROUTING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] mod_sofia.c:158 sofia/external/12345678910 SOFIA ROUTING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_ivr_originate.c:67 (sofia/external/12345678910) State Change CS_ROUTING -> CS_CONSUME_MEDIA

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/12345678910) State ROUTING going to sleep

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/12345678910) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:659 (sofia/external/12345678910) State CONSUME_MEDIA

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] switch_core_state_machine.c:659 (sofia/external/12345678910) State CONSUME_MEDIA going to sleep

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:35.790020 99.97% [DEBUG] sofia.c:7493 Channel sofia/external/12345678910 entering state [calling][0]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.869971 99.97% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:35.869971 99.97% [DEBUG] switch_core_io.c:448 Setting BUG Codec G722:9

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.609971 99.80% [DEBUG] sofia.c:7493 Channel sofia/external/12345678910 entering state [proceeding][180]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.609971 99.80% [NOTICE] sofia.c:7604 Ring-Ready sofia/external/12345678910!

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.609971 99.80% [DEBUG] switch_channel.c:3513 (sofia/external/12345678910) Callstate Change DOWN -> RINGING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] sofia.c:7493 Channel sofia/external/12345678910 entering state [proceeding][183]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] sofia.c:7503 Remote SDP:

93d9cb4b-4db0-43b8-8659-64c9afbdb46a v=0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a o=Sonus_UAC 977015 773498 IN IP4 67.231.1.110

93d9cb4b-4db0-43b8-8659-64c9afbdb46a s=SIP Media Capabilities

93d9cb4b-4db0-43b8-8659-64c9afbdb46a c=IN IP4 x.x.x.x

93d9cb4b-4db0-43b8-8659-64c9afbdb46a t=0 0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a m=audio 36834 RTP/AVP 0 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:0 PCMU/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:101 telephone-event/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=fmtp:101 0-15

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=ptime:20

93d9cb4b-4db0-43b8-8659-64c9afbdb46a

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [NOTICE] sofia.c:7506 Pre-Answer sofia/external/12345678910!

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_channel.c:3585 (sofia/external/12345678910) Callstate Change RINGING -> EARLY

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:3734 Set Codec sofia/external/12345678910 PCMU/8000 20 ms 160 samples 64000 bits 1 channels

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_codec.c:111 sofia/external/12345678910 Original read codec set to PCMU:0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5856 sofia/external/12345678910 Set 2833 dtmf send payload to 101 recv payload to 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/external/12345678910] 192.168.1.7 port 20660 -> x.x.x.x port 36834 codec: 0 ms: 20

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:8972 sofia/external/12345678910 Set 2833 dtmf send payload to 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:8979 sofia/external/12345678910 Set 2833 dtmf receive payload to 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:9002 sofia/external/12345678910 Set rtp dtmf delay to 40

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/external/12345678910] Peer UUID: 93d9cb4b-4db0-43b8-8659-64c9afbdb46a

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_ivr_bridge.c:1791 (sofia/external/12345678910) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA

**********************------------------------------------------------------------**************************

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_state_machine.c:581 (sofia/external/12345678910) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_state_machine.c:650 (sofia/external/12345678910) State EXCHANGE_MEDIA

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:43.649973 99.80% [NOTICE] switch_core_media.c:15991 Activating write resampler

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.669952 99.80% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.689969 99.80% [NOTICE] switch_core_media.c:15991 Activating write resampler

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.609971 99.80% [DEBUG] sofia.c:7493 Channel sofia/external/12345678910 entering state [proceeding][180]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.609971 99.80% [NOTICE] sofia.c:7604 Ring-Ready sofia/external/12345678910!

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.609971 99.80% [DEBUG] switch_channel.c:3513 (sofia/external/12345678910) Callstate Change DOWN -> RINGING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] sofia.c:7493 Channel sofia/external/12345678910 entering state [proceeding][183]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] sofia.c:7503 Remote SDP:

93d9cb4b-4db0-43b8-8659-64c9afbdb46a v=0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a o=Sonus_UAC 977015 773498 IN IP4 67.231.1.110

93d9cb4b-4db0-43b8-8659-64c9afbdb46a s=SIP Media Capabilities

93d9cb4b-4db0-43b8-8659-64c9afbdb46a c=IN IP4 x.x.x.x

**********************------------------------------------------------------------**************************

93d9cb4b-4db0-43b8-8659-64c9afbdb46a t=0 0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a m=audio 36834 RTP/AVP 0 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:0 PCMU/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:101 telephone-event/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=fmtp:101 0-15

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=ptime:20

93d9cb4b-4db0-43b8-8659-64c9afbdb46a

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [NOTICE] sofia.c:7506 Pre-Answer sofia/external/12345678910!

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_channel.c:3585 (sofia/external/12345678910) Callstate Change RINGING -> EARLY

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:3734 Set Codec sofia/external/12345678910 PCMU/8000 20 ms 160 samples 64000 bits 1 channels

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_codec.c:111 sofia/external/12345678910 Original read codec set to PCMU:0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:5856 sofia/external/12345678910 Set 2833 dtmf send payload to 101 recv payload to 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/external/12345678910] 192.168.1.7 port 20660 -> x.x.x.x port 36834 codec: 0 ms: 20

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:8972 sofia/external/12345678910 Set 2833 dtmf send payload to 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:8979 sofia/external/12345678910 Set 2833 dtmf receive payload to 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_media.c:9002 sofia/external/12345678910 Set rtp dtmf delay to 40

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/external/12345678910] Peer UUID: 93d9cb4b-4db0-43b8-8659-64c9afbdb46a

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_ivr_bridge.c:1791 (sofia/external/12345678910) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_state_machine.c:581 (sofia/external/12345678910) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] switch_core_state_machine.c:650 (sofia/external/12345678910) State EXCHANGE_MEDIA

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.629978 99.80% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:03:43.649973 99.80% [NOTICE] switch_core_media.c:15991 Activating write resampler

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.669952 99.80% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:03:43.689969 99.80% [NOTICE] switch_core_media.c:15991 Activating write resampler

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:09.229968 99.17% [DEBUG] sofia.c:7493 Channel sofia/external/12345678910 entering state [completing][200]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:09.229968 99.17% [DEBUG] sofia.c:7500 Duplicate SDP

93d9cb4b-4db0-43b8-8659-64c9afbdb46a v=0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a o=Sonus_UAC 977015 773498 IN IP4 67.231.1.110

93d9cb4b-4db0-43b8-8659-64c9afbdb46a s=SIP Media Capabilities

93d9cb4b-4db0-43b8-8659-64c9afbdb46a c=IN IP4 x.x.x.x

93d9cb4b-4db0-43b8-8659-64c9afbdb46a t=0 0

93d9cb4b-4db0-43b8-8659-64c9afbdb46a m=audio 36834 RTP/AVP 0 101

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:0 PCMU/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=rtpmap:101 telephone-event/8000

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=fmtp:101 0-15

93d9cb4b-4db0-43b8-8659-64c9afbdb46a a=ptime:20

93d9cb4b-4db0-43b8-8659-64c9afbdb46a

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:09.249972 99.17% [DEBUG] sofia.c:7493 Channel sofia/external/12345678910 entering state [ready][200]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:09.249972 99.17% [NOTICE] sofia.c:8622 Channel [sofia/external/12345678910] has been answered

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:09.249972 99.17% [DEBUG] switch_channel.c:3912 (sofia/external/12345678910) Callstate Change EARLY -> ACTIVE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.249972 99.17% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/internal/4707@fusionpbx.example.com.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.249972 99.17% [DEBUG] mod_sofia.c:914 Local SDP sofia/internal/4707@fusionpbx.example.com:

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea v=0

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea o=FreeSWITCH 1729688253 1729688255 IN IP4 192.168.1.7

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea s=FreeSWITCH

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea c=IN IP4 192.168.1.7

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea t=0 0

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea m=audio 18362 RTP/AVP 9 127

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:9 G722/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=rtpmap:127 telephone-event/8000

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=fmtp:127 0-15

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=ptime:20

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea a=sendrecv

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.249972 99.17% [NOTICE] switch_ivr_bridge.c:730 Channel [sofia/internal/4707@fusionpbx.example.com] has been answered

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.249972 99.17% [DEBUG] switch_channel.c:3912 (sofia/internal/4707@fusionpbx.example.com) Callstate Change EARLY -> ACTIVE

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.249972 99.17% [DEBUG] sofia.c:7493 Channel sofia/internal/4707@fusionpbx.example.com entering state [completed][200]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.269970 99.17% [DEBUG] switch_ivr_async.c:1778 No silence detection configured; assuming start of speech

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.269970 99.17% [DEBUG] sofia.c:7493 Channel sofia/internal/4707@fusionpbx.example.com entering state [ready][200]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:09.309961 99.17% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:09.309961 99.17% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.389967 99.33% [NOTICE] sofia.c:1065 Hangup sofia/internal/4707@fusionpbx.example.com [CS_EXECUTE] [NORMAL_CLEARING]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.389967 99.33% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/internal/4707@fusionpbx.example.com]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_ivr_bridge.c:819 sofia/internal/4707@fusionpbx.example.com ending bridge by request from write function

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/external/12345678910]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [NOTICE] switch_ivr_bridge.c:1027 Hangup sofia/external/12345678910 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:650 (sofia/external/12345678910) State EXCHANGE_MEDIA going to sleep

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/12345678910) Running State Change CS_HANGUP (Cur 2 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:844 (sofia/external/12345678910) Callstate Change ACTIVE -> HANGUP

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_ivr_bridge.c:1890 sofia/external/12345678910 skip receive message [UNBRIDGE] (channel is hungup already)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_ivr_bridge.c:1893 sofia/internal/4707@fusionpbx.example.com skip receive message [UNBRIDGE] (channel is hungup already)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/external/12345678910) State HANGUP

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] mod_sofia.c:463 sofia/external/12345678910 Overriding SIP cause 480 with 200 from the other leg

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] mod_sofia.c:469 Channel sofia/external/12345678910 hanging up, cause: NORMAL_CLEARING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/external/12345678910

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:59 sofia/external/12345678910 Standard HANGUP, cause: NORMAL_CLEARING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/external/12345678910) State HANGUP going to sleep

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:616 (sofia/external/12345678910) State Change CS_HANGUP -> CS_REPORTING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/12345678910) Running State Change CS_REPORTING (Cur 2 Tot 4980)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_session.c:2979 sofia/internal/4707@fusionpbx.example.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/4707@fusionpbx.example.com) State EXECUTE going to sleep

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4707@fusionpbx.example.com) Running State Change CS_HANGUP (Cur 2 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/external/12345678910) State REPORTING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:168 sofia/external/12345678910 Standard REPORTING, cause: NORMAL_CLEARING

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/external/12345678910) State REPORTING going to sleep

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_ivr_async.c:1597 Stop recording file /var/lib/freeswitch/recordings/fusionpbx.example.com/archive/2024/Oct/23/59aea0d8-c8e4-4ed5-822e-a49c515ea6ea.wav

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:607 (sofia/external/12345678910) State Change CS_REPORTING -> CS_DESTROY

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_session.c:1744 Session 4980 (sofia/external/12345678910) Locked, Waiting on external entities

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [NOTICE] switch_core_session.c:1762 Session 4980 (sofia/external/12345678910) Ended

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [NOTICE] switch_core_session.c:1766 Close Channel sofia/external/12345678910 [CS_DESTROY]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_ivr_async.c:1678 Channel is hung up

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:735 (sofia/external/12345678910) Running State Change CS_DESTROY (Cur 1 Tot 4980)

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/external/12345678910) State DESTROY

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] mod_sofia.c:380 sofia/external/12345678910 SOFIA DESTROY

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:175 sofia/external/12345678910 Standard DESTROY

93d9cb4b-4db0-43b8-8659-64c9afbdb46a 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/external/12345678910) State DESTROY going to sleep

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_media_bug.c:1326 Removing BUG from sofia/internal/4707@fusionpbx.example.com

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/4707@fusionpbx.example.com) Callstate Change ACTIVE -> HANGUP

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/4707@fusionpbx.example.com) State HANGUP

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] mod_sofia.c:469 Channel sofia/internal/4707@fusionpbx.example.com hanging up, cause: NORMAL_CLEARING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:59 sofia/internal/4707@fusionpbx.example.com Standard HANGUP, cause: NORMAL_CLEARING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/4707@fusionpbx.example.com) State HANGUP going to sleep

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/4707@fusionpbx.example.com) State Change CS_HANGUP -> CS_REPORTING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4707@fusionpbx.example.com) Running State Change CS_REPORTING (Cur 1 Tot 4980)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/4707@fusionpbx.example.com) State REPORTING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:168 sofia/internal/4707@fusionpbx.example.com Standard REPORTING, cause: NORMAL_CLEARING

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/4707@fusionpbx.example.com) State REPORTING going to sleep

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/4707@fusionpbx.example.com) State Change CS_REPORTING -> CS_DESTROY

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_session.c:1744 Session 4979 (sofia/internal/4707@fusionpbx.example.com) Locked, Waiting on external entities

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [NOTICE] switch_core_session.c:1762 Session 4979 (sofia/internal/4707@fusionpbx.example.com) Ended

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/4707@fusionpbx.example.com [CS_DESTROY]

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/4707@fusionpbx.example.com) Running State Change CS_DESTROY (Cur 0 Tot 4980)

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/4707@fusionpbx.example.com) State DESTROY

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] mod_sofia.c:380 sofia/internal/4707@fusionpbx.example.com SOFIA DESTROY

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:175 sofia/internal/4707@fusionpbx.example.com Standard DESTROY

59aea0d8-c8e4-4ed5-822e-a49c515ea6ea 2024-10-23 10:04:22.409958 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/4707@fusionpbx.example.com) State DESTROY going to sleep

pbxgeek · Oct 30, 2024

The two calls you shared have a few significant differences that could explain why the second one has weird noises on the line. Let me break them down:

Audio Codec Difference:
- In the first call, the codec used is primarily G722 (9), which is a wideband codec (high-definition audio), providing better sound quality.
- In the second call, the codec used is PCMU (0), also known as G.711 (µ-law), which is a narrowband codec with lower audio quality. This codec transmits audio at a lower frequency range.
Codec Negotiation:
- In the first call, FreeSWITCH compares multiple high-quality codecs (G7221, SIREN14, G722, etc.) and finally selects G722 (9) as the codec for the call. This ensures better audio quality.
- In the second call, FreeSWITCH negotiates and ultimately selects PCMU (0), which is lower quality compared to G722. Since PCMU is commonly used for compatibility but not for high-quality audio
Network Path and Ports:
- The first call uses RTP on port 18362 from IP 192.168.1.7 to 192.168.1.106. This is a local (LAN) IP address, likely indicating a more stable local network connection.
- The second call uses RTP on port 20660 from 192.168.1.7 to an external IP (not shared in the log). External network connections may introduce latency, jitter, or packet loss, all of which can contribute to weird noises (artifacts) on the call.

nocstaff · Nov 1, 2024

Thank you for your reply. Why would 2 different calls from the same phone to the same number back to back be routed differently? How do I make sure all calls are routed so this isn't happening.

nocstaff · Nov 1, 2024

Here is the latest call with the problem:

Bottom of Form

Displaying the last 32,768 of 5,952,976 bytes.

0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(from_user_record=)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [from_user_record]=[UNDEF]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com parsing [fusionpbx.domain.com->rtp_has_crypto] continue=true
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [rtp_has_crypto] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=on-false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com parsing [fusionpbx.domain.com->redial] continue=true
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [redial] destination_number(19075392112) =~ /^(redial|\*870)$/ break=on-true
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [redial] () =~ // break=never
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com parsing [fusionpbx.domain.com->speed_dial] continue=false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [speed_dial] destination_number(19075392112) =~ /^\*0(.*)$/ break=on-false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com parsing [fusionpbx.domain.com->user_hold_music] continue=true
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com parsing [fusionpbx.domain.com->default_caller_id] continue=true
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com parsing [fusionpbx.domain.com->sip.urbancom.net.9.11d] continue=false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [sip.urbancom.net.9.11d] ${user_exists}(false) =~ /false/ break=on-false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Regex (PASS) [sip.urbancom.net.9.11d] destination_number(19075392112) =~ /^\+?(\d{11})$/ break=on-false
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action export(call_direction=outbound) INLINE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com export(call_direction=outbound)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[outbound]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action unset(call_timeout)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(hangup_after_bridge=true)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(effective_caller_id_name=${outbound_caller_id_name})
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(effective_caller_id_number=${outbound_caller_id_number})
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(inherit_codec=true)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(ignore_display_updates=true)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(callee_id_number=19075392112)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action set(outbound_prefix=9)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 Dialplan: sofia/internal/4703@fusionpbx.domain.com Action bridge(sofia/gateway/b9083346-cd00-4461-902f-acf304b956d2/19075392112)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/4703@fusionpbx.domain.com) State Change CS_ROUTING -> CS_EXECUTE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/4703@fusionpbx.domain.com) State ROUTING going to sleep
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4703@fusionpbx.domain.com) Running State Change CS_EXECUTE (Cur 1 Tot 1298)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/4703@fusionpbx.domain.com) State EXECUTE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_sofia.c:213 sofia/internal/4703@fusionpbx.domain.com SOFIA EXECUTE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:323 sofia/internal/4703@fusionpbx.domain.com Standard EXECUTE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(caller_id_number=4703)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [caller_id_number]=[4703]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(RFC2822_DATE=Fri, 01 Nov 2024 11:43:47 -0800)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [RFC2822_DATE]=[Fri, 01 Nov 2024 11:43:47 -0800]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com export(origination_callee_id_name=19075392112)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [origination_callee_id_name]=[19075392112]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com hash(insert/fusionpbx.domain.com-last_dial/4703/19075392112)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com unset(call_timeout)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(hangup_after_bridge=true)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [hangup_after_bridge]=[true]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(effective_caller_id_name=Urban Pain)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [effective_caller_id_name]=[Urban Pain]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(effective_caller_id_number=9072777246)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [effective_caller_id_number]=[9072777246]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(inherit_codec=true)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [inherit_codec]=[true]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(ignore_display_updates=true)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [ignore_display_updates]=[true]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(callee_id_number=19075392112)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [callee_id_number]=[19075392112]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com set(outbound_prefix=9)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_dptools.c:1671 SET sofia/internal/4703@fusionpbx.domain.com [outbound_prefix]=[9]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 EXECUTE [depth=0] sofia/internal/4703@fusionpbx.domain.com bridge(sofia/gateway/b9083346-cd00-4461-902f-acf304b956d2/19075392112)
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_channel.c:1288 sofia/internal/4703@fusionpbx.domain.com EXPORTING[export_vars] [domain_name]=[fusionpbx.domain.com] to event
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_channel.c:1288 sofia/internal/4703@fusionpbx.domain.com EXPORTING[export_vars] [domain_uuid]=[1acc08e3-df23-4d9b-bd2f-7b75da782a62] to event
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_channel.c:1288 sofia/internal/4703@fusionpbx.domain.com EXPORTING[export_vars] [call_direction]=[outbound] to event
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_channel.c:1288 sofia/internal/4703@fusionpbx.domain.com EXPORTING[export_vars] [call_direction]=[outbound] to event
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_channel.c:1288 sofia/internal/4703@fusionpbx.domain.com EXPORTING[export_vars] [origination_callee_id_name]=[19075392112] to event
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [NOTICE] switch_channel.c:1142 New Channel sofia/external/19075392112 [c8dce204-06bb-45b3-8afc-23d5dd3fa053]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_sofia.c:5110 (sofia/external/19075392112) State Change CS_NEW -> CS_INIT
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/external/19075392112) Running State Change CS_INIT (Cur 2 Tot 1299)
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/external/19075392112) State INIT
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_sofia.c:97 sofia/external/19075392112 SOFIA INIT
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [INFO] sofia_glue.c:1659 sofia/external/19075392112 sending invite call-id: (null)
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] sofia_glue.c:1662 sofia/external/19075392112 sending invite version: 1.10.10 -release 64bit
c8dce204-06bb-45b3-8afc-23d5dd3fa053 Local SDP:
c8dce204-06bb-45b3-8afc-23d5dd3fa053 v=0
c8dce204-06bb-45b3-8afc-23d5dd3fa053 o=FreeSWITCH 1730464293 1730464294 IN IP4 206.174.47.188
c8dce204-06bb-45b3-8afc-23d5dd3fa053 s=FreeSWITCH
c8dce204-06bb-45b3-8afc-23d5dd3fa053 c=IN IP4 206.174.47.188
c8dce204-06bb-45b3-8afc-23d5dd3fa053 t=0 0
c8dce204-06bb-45b3-8afc-23d5dd3fa053 m=audio 25934 RTP/AVP 9 0 8 101
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:9 G722/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:0 PCMU/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:8 PCMA/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:101 telephone-event/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=fmtp:101 0-15
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=silenceSupp

ff - - - -
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=ptime:20
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=sendrecv
c8dce204-06bb-45b3-8afc-23d5dd3fa053
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:40 sofia/external/19075392112 Standard INIT
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:48 (sofia/external/19075392112) State Change CS_INIT -> CS_ROUTING
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/external/19075392112) State INIT going to sleep
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/external/19075392112) Running State Change CS_ROUTING (Cur 2 Tot 1299)
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:640 (sofia/external/19075392112) State ROUTING
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] mod_sofia.c:158 sofia/external/19075392112 SOFIA ROUTING
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_ivr_originate.c:67 (sofia/external/19075392112) State Change CS_ROUTING -> CS_CONSUME_MEDIA
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:640 (sofia/external/19075392112) State ROUTING going to sleep
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/external/19075392112) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 1299)
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:659 (sofia/external/19075392112) State CONSUME_MEDIA
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] switch_core_state_machine.c:659 (sofia/external/19075392112) State CONSUME_MEDIA going to sleep
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:47.885606 99.70% [DEBUG] sofia.c:7493 Channel sofia/external/19075392112 entering state [calling][0]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] sofia.c:7493 Channel sofia/external/19075392112 entering state [proceeding][183]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] sofia.c:7503 Remote SDP:
c8dce204-06bb-45b3-8afc-23d5dd3fa053 v=0
c8dce204-06bb-45b3-8afc-23d5dd3fa053 o=Sonus_UAC 79 54816 IN IP4 67.231.1.110
c8dce204-06bb-45b3-8afc-23d5dd3fa053 s=SIP Media Capabilities
c8dce204-06bb-45b3-8afc-23d5dd3fa053 c=IN IP4 64.7.96.3
c8dce204-06bb-45b3-8afc-23d5dd3fa053 t=0 0
c8dce204-06bb-45b3-8afc-23d5dd3fa053 m=audio 47270 RTP/AVP 0 101
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:0 PCMU/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:101 telephone-event/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=fmtp:101 0-15
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=ptime:20
c8dce204-06bb-45b3-8afc-23d5dd3fa053
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [NOTICE] sofia.c:7506 Pre-Answer sofia/external/19075392112!
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_channel.c:3585 (sofia/external/19075392112) Callstate Change DOWN -> EARLY
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:3734 Set Codec sofia/external/19075392112 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_codec.c:111 sofia/external/19075392112 Original read codec set to PCMU:0
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:5856 sofia/external/19075392112 Set 2833 dtmf send payload to 101 recv payload to 101
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/external/19075392112] 192.168.1.7 port 25934 -> 64.7.96.3 port 47270 codec: 0 ms: 20
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:8972 sofia/external/19075392112 Set 2833 dtmf send payload to 101
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:8979 sofia/external/19075392112 Set 2833 dtmf receive payload to 101
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.785679 99.70% [DEBUG] switch_core_media.c:9002 sofia/external/19075392112 Set rtp dtmf delay to 40
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_ivr_originate.c:451 Setting codec string on sofia/internal/4703@fusionpbx.domain.com to PCMU@8000h@20i
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [INFO] switch_ivr_originate.c:3858 Sending early media
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 127@8000
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:3734 Set Codec sofia/internal/4703@fusionpbx.domain.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_codec.c:111 sofia/internal/4703@fusionpbx.domain.com Original read codec set to PCMU:0
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 127@8000
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:5856 sofia/internal/4703@fusionpbx.domain.com Set 2833 dtmf send payload to 127 recv payload to 127
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:6192 No matches with FTMP, fallback to ignoring FMTP
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:6200 No matches with inherit_codec, fallback to ignoring PT
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/internal/4703@fusionpbx.domain.com] 192.168.1.7 port 29882 -> 192.168.1.111 port 2250 codec: 0 ms: 20
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:8972 sofia/internal/4703@fusionpbx.domain.com Set 2833 dtmf send payload to 127
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:8979 sofia/internal/4703@fusionpbx.domain.com Set 2833 dtmf receive payload to 127
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:9002 sofia/internal/4703@fusionpbx.domain.com Set rtp dtmf delay to 40
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [NOTICE] sofia_media.c:90 Pre-Answer sofia/internal/4703@fusionpbx.domain.com!
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_channel.c:3585 (sofia/internal/4703@fusionpbx.domain.com) Callstate Change RINGING -> EARLY
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/internal/4703@fusionpbx.domain.com.
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] mod_sofia.c:2607 Ring SDP:
3f84a4c3-0f00-475a-931f-f8d1a117ff21 v=0
3f84a4c3-0f00-475a-931f-f8d1a117ff21 o=FreeSWITCH 1730460348 1730460349 IN IP4 192.168.1.7
3f84a4c3-0f00-475a-931f-f8d1a117ff21 s=FreeSWITCH
3f84a4c3-0f00-475a-931f-f8d1a117ff21 c=IN IP4 192.168.1.7
3f84a4c3-0f00-475a-931f-f8d1a117ff21 t=0 0
3f84a4c3-0f00-475a-931f-f8d1a117ff21 m=audio 29882 RTP/AVP 0 127
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=rtpmap:0 PCMU/8000
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=rtpmap:127 telephone-event/8000
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=fmtp:127 0-15
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=ptime:20
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=sendrecv
3f84a4c3-0f00-475a-931f-f8d1a117ff21 m=video 0 RTP/AVP 19
3f84a4c3-0f00-475a-931f-f8d1a117ff21
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/external/19075392112] Peer UUID: c8dce204-06bb-45b3-8afc-23d5dd3fa053
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.805675 99.70% [DEBUG] sofia.c:7493 Channel sofia/internal/4703@fusionpbx.domain.com entering state [early][183]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_ivr_bridge.c:1791 (sofia/external/19075392112) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/external/19075392112) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 1299)
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.805675 99.70% [DEBUG] switch_core_state_machine.c:650 (sofia/external/19075392112) State EXCHANGE_MEDIA
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.805675 99.70% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:50.845678 99.70% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:50.905674 99.70% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:58.045679 99.67% [DEBUG] sofia.c:7493 Channel sofia/external/19075392112 entering state [completing][200]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:58.045679 99.67% [DEBUG] sofia.c:7500 Duplicate SDP
c8dce204-06bb-45b3-8afc-23d5dd3fa053 v=0
c8dce204-06bb-45b3-8afc-23d5dd3fa053 o=Sonus_UAC 79 54816 IN IP4 67.231.1.110
c8dce204-06bb-45b3-8afc-23d5dd3fa053 s=SIP Media Capabilities
c8dce204-06bb-45b3-8afc-23d5dd3fa053 c=IN IP4 64.7.96.3
c8dce204-06bb-45b3-8afc-23d5dd3fa053 t=0 0
c8dce204-06bb-45b3-8afc-23d5dd3fa053 m=audio 47270 RTP/AVP 0 101
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:0 PCMU/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=rtpmap:101 telephone-event/8000
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=fmtp:101 0-15
c8dce204-06bb-45b3-8afc-23d5dd3fa053 a=ptime:20
c8dce204-06bb-45b3-8afc-23d5dd3fa053
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:58.065689 99.67% [DEBUG] sofia.c:7493 Channel sofia/external/19075392112 entering state [ready][200]
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:58.065689 99.67% [NOTICE] sofia.c:8622 Channel [sofia/external/19075392112] has been answered
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:58.065689 99.67% [DEBUG] switch_channel.c:3912 (sofia/external/19075392112) Callstate Change EARLY -> ACTIVE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:58.085642 99.67% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/internal/4703@fusionpbx.domain.com.
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:58.085642 99.67% [DEBUG] mod_sofia.c:914 Local SDP sofia/internal/4703@fusionpbx.domain.com:
3f84a4c3-0f00-475a-931f-f8d1a117ff21 v=0
3f84a4c3-0f00-475a-931f-f8d1a117ff21 o=FreeSWITCH 1730460348 1730460350 IN IP4 192.168.1.7
3f84a4c3-0f00-475a-931f-f8d1a117ff21 s=FreeSWITCH
3f84a4c3-0f00-475a-931f-f8d1a117ff21 c=IN IP4 192.168.1.7
3f84a4c3-0f00-475a-931f-f8d1a117ff21 t=0 0
3f84a4c3-0f00-475a-931f-f8d1a117ff21 m=audio 29882 RTP/AVP 0 127
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=rtpmap:0 PCMU/8000
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=rtpmap:127 telephone-event/8000
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=fmtp:127 0-15
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=ptime:20
3f84a4c3-0f00-475a-931f-f8d1a117ff21 a=sendrecv
3f84a4c3-0f00-475a-931f-f8d1a117ff21
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:58.085642 99.67% [NOTICE] switch_ivr_bridge.c:730 Channel [sofia/internal/4703@fusionpbx.domain.com] has been answered
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:58.085642 99.67% [DEBUG] switch_channel.c:3912 (sofia/internal/4703@fusionpbx.domain.com) Callstate Change EARLY -> ACTIVE
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:58.085642 99.67% [DEBUG] sofia.c:7493 Channel sofia/internal/4703@fusionpbx.domain.com entering state [completed][200]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:58.105638 99.67% [DEBUG] sofia.c:7493 Channel sofia/internal/4703@fusionpbx.domain.com entering state [ready][200]
3f84a4c3-0f00-475a-931f-f8d1a117ff21 2024-11-01 11:43:58.125663 99.67% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
c8dce204-06bb-45b3-8afc-23d5dd3fa053 2024-11-01 11:43:58.125663 99.67% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.

pbxgeek · Nov 8, 2024

I can't answer why the same call goes through different paths. It seems like an issue with dialplan or how the number is dialed.

Search

Search

Water flow at Beach and Seagull Noises randomly when calling out

nocstaff

New Member

pbxgeek

Active Member

nocstaff

New Member

pbxgeek

Active Member

nocstaff

New Member

pbxgeek

Active Member

nocstaff

New Member

nocstaff

New Member

pbxgeek

Active Member

Water flow at Beach and Seagull Noises randomly when calling out

New Member

Active Member

New Member

Active Member

New Member

Active Member

​

New Member

New Member

Active Member