Call block not working

Status
Not open for further replies.
W

Win

Member
Feb 12, 2024
49
1
8
Hi,
I'm trying outbound call block, but it's not working, I have following a thread here, someone there suggested that move the call_block's order from 40 to 26, I have tried it but I still able to call the number.

any body had experienced on this?
 
M

markjcrane

Active Member
Staff member
Jul 22, 2018
448
162
43
49
Make sure to leave the caller ID name field empty. Its more effective to block using the Caller ID number.
 
W

Win

Member
Feb 12, 2024
49
1
8
Hi @markjcrane

I tried it, but I still able to outbound call to that number..

is this because of:

Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [call_block] ${call_direction}(local) =~ /^(inbound|outbound)$/ break=on-false


=== complete fs log ===

freeswitch@pbx> sofia profile external siptrace on
Enabled sip debugging on external
2024-02-20 13:18:10.859985 96.60% [NOTICE] switch_channel.c:1142 New Channel sof ia/internal/101@pbx.example.co.id [8d10c308-625d-4d6e-99e1-e413cfba322c]
2024-02-20 13:18:10.859985 96.60% [DEBUG] switch_core_state_machine.c:581 (sofia /internal/101@pbx.example.co.id) Running State Change CS_NEW (Cur 1 Tot 7)
2024-02-20 13:18:10.859985 96.60% [INFO] sofia.c:10459 sofia/internal/101@pbx.pt digital.co.id receiving invite from 175.158.58.169:61306 version: 1.10.11 -relea se 64bit call-id: e4ffdd30466a473f957fcba9667d11b8
2024-02-20 13:18:10.859985 96.60% [DEBUG] sofia.c:10553 verifying acl "providers " for ip/port 175.158.58.169:0.
2024-02-20 13:18:10.859985 96.60% [DEBUG] switch_core_state_machine.c:600 (sofia /internal/101@pbx.example.co.id) State NEW
2024-02-20 13:18:10.859985 96.60% [DEBUG] sofia.c:2419 detaching session 8d10c30 8-625d-4d6e-99e1-e413cfba322c
2024-02-20 13:18:10.859985 96.60% [DEBUG] sofia.c:2532 Re-attaching to session 8 d10c308-625d-4d6e-99e1-e413cfba322c
2024-02-20 13:18:10.879985 96.60% [INFO] sofia.c:10459 sofia/internal/101@pbx.pt digital.co.id receiving invite from 175.158.58.169:61306 version: 1.10.11 -relea se 64bit call-id: e4ffdd30466a473f957fcba9667d11b8
2024-02-20 13:18:10.879985 96.60% [DEBUG] sofia.c:10553 verifying acl "providers " for ip/port 175.158.58.169:0.
2024-02-20 13:18:10.879985 96.60% [DEBUG] sofia.c:7493 Channel sofia/internal/10 1@pbx.example.co.id entering state [received][100]
2024-02-20 13:18:10.879985 96.60% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=- 3917423890 3917423890 IN IP4 175.158.58.169
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4010 RTP/AVP 8 0 101
c=IN IP4 175.158.58.169
b=TIAS:64000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtcp:4011 IN IP4 192.168.1.13
a=ssrc:1035886213 cname:058f22af33b51660

2024-02-20 13:18:10.879985 96.60% [DEBUG] sofia.c:7906 (sofia/internal/101@pbx.p tdigital.co.id) State Change CS_NEW -> CS_INIT
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:581 (sofia /internal/101@pbx.example.co.id) Running State Change CS_INIT (Cur 1 Tot 7)
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:624 (sofia /internal/101@pbx.example.co.id) State INIT
2024-02-20 13:18:10.879985 96.60% [DEBUG] mod_sofia.c:97 sofia/internal/101@pbx. example.co.id SOFIA INIT
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:40 sofia/i nternal/101@pbx.example.co.id Standard INIT
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:48 (sofia/ internal/101@pbx.example.co.id) State Change CS_INIT -> CS_ROUTING
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:624 (sofia /internal/101@pbx.example.co.id) State INIT going to sleep
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:581 (sofia /internal/101@pbx.example.co.id) Running State Change CS_ROUTING (Cur 1 Tot 7)
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_channel.c:2399 (sofia/internal/ 101@pbx.example.co.id) Callstate Change DOWN -> RINGING
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:640 (sofia /internal/101@pbx.example.co.id) State ROUTING
2024-02-20 13:18:10.879985 96.60% [DEBUG] mod_sofia.c:158 sofia/internal/101@pbx .example.co.id SOFIA ROUTING
2024-02-20 13:18:10.879985 96.60% [DEBUG] switch_core_state_machine.c:230 sofia/ internal/101@pbx.example.co.id Standard ROUTING
2024-02-20 13:18:10.879985 96.60% [INFO] mod_dialplan_xml.c:639 Processing 101 < 101>->0817145779 in context pbx.example.co.id
2024-02-20 13:18:10.879985 96.60% [NOTICE] switch_cpp.cpp:1466 [xml_handler] mul tiple key:dialplan:pbx.example.co.id
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->u ser_exists] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_exists] ${lo opback_leg}() =~ /^B$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_exists] () = ~ // break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id Action set(user_exists=${user_e xists id ${destination_number} ${domain_name}}) INLINE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(user_exists=false)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [user_exists]=[false]
Dialplan: sofia/internal/101@pbx.example.co.id Action set(from_user_exists=${u ser_exists id ${sip_from_user} ${sip_from_host}}) INLINE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(from_user_exists=tr ue)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [from_user_exists]=[true]
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_exists] ${us er_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->c aller-details] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [caller-details] $ {caller_destination}() =~ /^$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Action set(caller_destination=$ {destination_number}) INLINE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(caller_destination= 0817145779)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [caller_destination]=[0817145779]
Dialplan: sofia/internal/101@pbx.example.co.id Action set(caller_id_name=${cal ler_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(caller_id_name=101)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [caller_id_name]=[101]
Dialplan: sofia/internal/101@pbx.example.co.id Action set(caller_id_number=${c aller_id_number})
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->g lobal-variables] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id Action set(RFC2822_DATE=${strft ime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->d omain-variables] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [domain-variables] () =~ // break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id Action export(origination_calle e_id_name=${caller_destination})
Dialplan: sofia/internal/101@pbx.example.co.id Action set(operator=1000) INLIN E
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(operator=1000)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [operator]=[1000]
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->c lear_sip_auto_answer] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [clear_sip_auto_an swer] ${click_to_call}() =~ /true/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->i s_loopback] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [is_loopback] ${is _follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->c all-direction] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [call-direction] $ {call_direction}() =~ /^$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Action export(call_direction=lo cal) INLINE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id export(call_direction=l ocal)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1334 EXPORT (export_v ars) [call_direction]=[local]
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->c all_block] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [call_block] ${cal l_direction}(local) =~ /^(inbound|outbound)$/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->u ser_record] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${ca ll_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${ca ll_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_record] ${ca ll_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${us er_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_record] ${fr om_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Action set(from_user_record=${u ser_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(from_user_record=)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [from_user_record]=[UNDEF]
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_record] ${fr om_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${fr om_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_record] ${fr om_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${ca ll_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${fr om_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_record] ${fr om_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${ca ll_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${fr om_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_record] ${fr om_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [user_record] ${ca ll_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${fr om_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_record] ${re cord_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->r tp_has_crypto] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [rtp_has_crypto] $ {rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_S HA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|A ES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=on- false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->r edial] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [redial] destinati on_number(0817145779) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/101@pbx.example.co.id Action hash(insert/${domain_nam e}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->s peed_dial] continue=false
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [speed_dial] desti nation_number(0817145779) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->u ser_hold_music] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->d efault_caller_id] continue=true
Dialplan: sofia/internal/101@pbx.example.co.id Regex (FAIL) [default_caller_id ] ${call_direction}(local) =~ /outbound/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->6 22131180035-Out] continue=false
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [622131180035-Out] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [622131180035-Out] destination_number(0817145779) =~ /^(?:\+?62|0)(\d+)$/ break=on-false
Dialplan: sofia/internal/101@pbx.example.co.id Action export(call_direction=ou tbound) INLINE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id export(call_direction=o utbound)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1334 EXPORT (export_v ars) [call_direction]=[outbound]
Dialplan: sofia/internal/101@pbx.example.co.id Action unset(call_timeout)
Dialplan: sofia/internal/101@pbx.example.co.id Action set(hangup_after_bridge= true)
Dialplan: sofia/internal/101@pbx.example.co.id Action set(effective_caller_id_ name=${outbound_caller_id_name})
Dialplan: sofia/internal/101@pbx.example.co.id Action set(effective_caller_id_ number=${outbound_caller_id_number})
Dialplan: sofia/internal/101@pbx.example.co.id Action set(inherit_codec=true)
Dialplan: sofia/internal/101@pbx.example.co.id Action set(ignore_display_updat es=true)
Dialplan: sofia/internal/101@pbx.example.co.id Action set(callee_id_number=081 7145779)
Dialplan: sofia/internal/101@pbx.example.co.id 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)
Dialplan: sofia/internal/101@pbx.example.co.id Action bridge(sofia/gateway/ad6 76e11-b979-48bc-b88f-de56da4a0a0e/0817145779)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:281 (sofia /internal/101@pbx.example.co.id) State Change CS_ROUTING -> CS_EXECUTE
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:640 (sofia /internal/101@pbx.example.co.id) State ROUTING going to sleep
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:581 (sofia /internal/101@pbx.example.co.id) Running State Change CS_EXECUTE (Cur 1 Tot 7)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:647 (sofia /internal/101@pbx.example.co.id) State EXECUTE
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_sofia.c:213 sofia/internal/101@pbx .example.co.id SOFIA EXECUTE
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:323 sofia/ internal/101@pbx.example.co.id Standard EXECUTE
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(caller_id_number=10 1)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [caller_id_number]=[101]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(RFC2822_DATE=Tue, 2 0 Feb 2024 13:18:10 +0700)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [RFC2822_DATE]=[Tue, 20 Feb 2024 13:18:10 +0700]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id export(origination_call ee_id_name=0817145779)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1334 EXPORT (export_v ars) [origination_callee_id_name]=[0817145779]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id hash(insert/pbx.ptdigit al.co.id-last_dial/101/0817145779)
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id unset(call_timeout)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout ]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(hangup_after_bridge =true)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(effective_caller_id _name=)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [effective_caller_id_name]=[UNDEF]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(effective_caller_id _number=)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [effective_caller_id_number]=[UNDEF]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(inherit_codec=true)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [inherit_codec]=[true]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(ignore_display_upda tes=true)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [ignore_display_updates]=[true]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(callee_id_number=08 17145779)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [callee_id_number]=[0817145779]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id 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)
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_dptools.c:1671 SET sofia/internal/ 101@pbx.example.co.id [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,4 4,58,88,111,403,501,602,607,809]
EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id bridge(sofia/gateway/ad 676e11-b979-48bc-b88f-de56da4a0a0e/0817145779)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1288 sofia/internal/1 01@pbx.example.co.id EXPORTING[export_vars] [domain_name]=[pbx.example.co.id ] to event
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1288 sofia/internal/1 01@pbx.example.co.id EXPORTING[export_vars] [domain_uuid]=[77527d50-4d75-4756- 9b39-f4298ffc455c] to event
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1288 sofia/internal/1 01@pbx.example.co.id EXPORTING[export_vars] [call_direction]=[outbound] to eve nt
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1288 sofia/internal/1 01@pbx.example.co.id EXPORTING[export_vars] [call_direction]=[outbound] to eve nt
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_channel.c:1288 sofia/internal/1 01@pbx.example.co.id EXPORTING[export_vars] [origination_callee_id_name]=[0817 145779] to event
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_ivr_originate.c:2301 Parsing gl obal variables
2024-02-20 13:18:10.899991 96.60% [NOTICE] switch_channel.c:1142 New Channel sof ia/external/0817145779 [74cac10a-77f4-4caa-aa17-66e9e3958567]
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_sofia.c:5110 (sofia/external/08171 45779) State Change CS_NEW -> CS_INIT
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:581 (sofia /external/0817145779) Running State Change CS_INIT (Cur 2 Tot 8)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:624 (sofia /external/0817145779) State INIT
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_sofia.c:97 sofia/external/08171457 79 SOFIA INIT
2024-02-20 13:18:10.899991 96.60% [INFO] sofia_glue.c:1659 sofia/external/081714 5779 sending invite call-id: (null)
2024-02-20 13:18:10.899991 96.60% [DEBUG] sofia_glue.c:1662 sofia/external/08171 45779 sending invite version: 1.10.11 -release 64bit
Local SDP:
v=0
o=FreeSWITCH 1708387732 1708387733 IN IP4 149.129.248.234
s=FreeSWITCH
c=IN IP4 149.129.248.234
t=0 0
m=audio 22158 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:eek:ff - - - -
a=ptime:20
a=sendrecv

2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:40 sofia/e xternal/0817145779 Standard INIT
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:48 (sofia/ external/0817145779) State Change CS_INIT -> CS_ROUTING
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:624 (sofia /external/0817145779) State INIT going to sleep
send 1271 bytes to udp/[43.245.185.3]:5060 at 13:18:10.911253:
------------------------------------------------------------------------
INVITE sip:0817145779@43.245.185.3:5060 SIP/2.0
Via: SIP/2.0/UDP 149.129.248.234:5090;rport;branch=z9hG4bK7mNjUme9Q3U6p
Max-Forwards: 69
From: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
To: <sip:0817145779@43.245.185.3:5060>
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
CSeq: 79651153 INVITE
Contact: <sip:gw+ad676e11-b979-48bc-b88f-de56da4a0a0e@149.129.248.234:5090;trans port=udp;gw=ad676e11-b979-48bc-b88f-de56da4a0a0e>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER , NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-s eize, call-info, sla, include-session-description, presence.winfo, message-summa ry, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 277
X-FS-Support: update_display,send_info
Remote-Party-ID: "101" <sip:101@43.245.185.3:5060>;party=calling;screen=yes;priv acy=off

v=0
o=FreeSWITCH 1708387732 1708387733 IN IP4 149.129.248.234
s=FreeSWITCH
c=IN IP4 149.129.248.234
t=0 0
m=audio 22158 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:eek:ff - - - -
a=ptime:20

2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:581 (sofia /external/0817145779) Running State Change CS_ROUTING (Cur 2 Tot 8)
2024-02-20 13:18:10.899991 96.60% [DEBUG] sofia.c:7493 Channel sofia/external/08 17145779 entering state [calling][0]
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:640 (sofia /external/0817145779) State ROUTING
2024-02-20 13:18:10.899991 96.60% [DEBUG] mod_sofia.c:158 sofia/external/0817145 779 SOFIA ROUTING
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_ivr_originate.c:67 (sofia/exter nal/0817145779) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:640 (sofia /external/0817145779) State ROUTING going to sleep
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:581 (sofia /external/0817145779) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 8)
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:659 (sofia /external/0817145779) State CONSUME_MEDIA
2024-02-20 13:18:10.899991 96.60% [DEBUG] switch_core_state_machine.c:659 (sofia /external/0817145779) State CONSUME_MEDIA going to sleep
recv 323 bytes from udp/[43.245.185.3]:5060 at 13:18:10.913408:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 149.129.248.234:5090;rport=5090;received=149.129.248.234;branch =z9hG4bK7mNjUme9Q3U6p
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
From: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
To: <sip:0817145779@43.245.185.3:5060>
CSeq: 79651153 INVITE
Content-Length: 0


recv 696 bytes from udp/[43.245.185.3]:5060 at 13:18:11.878125:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 149.129.248.234:5090;rport=5090;received=149.129.248.234;branch =z9hG4bK7mNjUme9Q3U6p
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
From: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
To: <sip:0817145779@43.245.185.3:5060>;tag=b8565602-f3e2-4752-b490-8cb1f0e18fe6
CSeq: 79651153 INVITE
Contact: <sip:43.245.185.3:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, OPTIONS
Content-Type: application/sdp
Content-Length: 191

v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2024-02-20 13:18:11.859984 96.53% [DEBUG] sofia.c:7493 Channel sofia/external/08 17145779 entering state [proceeding][183]
2024-02-20 13:18:11.859984 96.53% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2024-02-20 13:18:11.859984 96.53% [NOTICE] sofia.c:7506 Pre-Answer sofia/externa l/0817145779!
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_channel.c:3585 (sofia/external/ 0817145779) Callstate Change DOWN -> EARLY
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:5588 Audio Codec C ompare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:5438 Set telephone -event payload to 101@8000
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:3731 Set Codec sof ia/external/0817145779 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_codec.c:111 sofia/external /0817145779 Original read codec set to PCMA:8
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:5798 Set telephone -event payload to 101@8000
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:5856 sofia/externa l/0817145779 Set 2833 dtmf send payload to 101 recv payload to 101
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_core_media.c:8660 AUDIO RTP [so fia/external/0817145779] 172.31.203.210 port 22158 -> 43.245.185.3 port 11010 co dec: 8 ms: 20
2024-02-20 13:18:11.859984 96.53% [DEBUG] switch_rtp.c:4563 Starting timer [soft ] 160 bytes per 20ms
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:8973 sofia/externa l/0817145779 Set 2833 dtmf send payload to 101
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:8980 sofia/externa l/0817145779 Set 2833 dtmf receive payload to 101
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:9003 sofia/externa l/0817145779 Set rtp dtmf delay to 40
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_ivr_originate.c:455 Setting cod ec string on sofia/internal/101@pbx.example.co.id to PCMA@8000h@20i
2024-02-20 13:18:11.879985 96.53% [INFO] switch_ivr_originate.c:3862 Sending ear ly media
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:5588 Audio Codec C ompare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:5438 Set telephone -event payload to 101@8000
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:3731 Set Codec sof ia/internal/101@pbx.example.co.id PCMA/8000 20 ms 160 samples 64000 bits 1 cha nnels
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_codec.c:111 sofia/internal /101@pbx.example.co.id Original read codec set to PCMA:8
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:5798 Set telephone -event payload to 101@8000
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:5856 sofia/interna l/101@pbx.example.co.id Set 2833 dtmf send payload to 101 recv payload to 101
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:8660 AUDIO RTP [so fia/internal/101@pbx.example.co.id] 172.31.203.210 port 17058 -> 175.158.58.16 9 port 4010 codec: 8 ms: 20
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_rtp.c:4563 Starting timer [soft ] 160 bytes per 20ms
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:8881 Activating RT CP PORT 4011
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_rtp.c:4895 RTCP send rate is: 1 000 and packet rate is: 20000 Remote Port: 4011
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_rtp.c:2689 Setting RTCP remote addr to 175.158.58.169:4011 2
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:8973 sofia/interna l/101@pbx.example.co.id Set 2833 dtmf send payload to 101
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:8980 sofia/interna l/101@pbx.example.co.id Set 2833 dtmf receive payload to 101
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:9003 sofia/interna l/101@pbx.example.co.id Set rtp dtmf delay to 40
2024-02-20 13:18:11.879985 96.53% [NOTICE] sofia_media.c:90 Pre-Answer sofia/int ernal/101@pbx.example.co.id!
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_channel.c:3585 (sofia/internal/ 101@pbx.example.co.id) Callstate Change RINGING -> EARLY
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/internal/101@pbx.example.co.id.
2024-02-20 13:18:11.879985 96.53% [DEBUG] mod_sofia.c:2607 Ring SDP:
v=0
o=FreeSWITCH 1708392833 1708392834 IN IP4 149.129.248.234
s=FreeSWITCH
c=IN IP4 149.129.248.234
t=0 0
m=audio 17058 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
a=rtcp:17059 IN IP4 149.129.248.234

2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_ivr_originate.c:3913 Originate Resulted in Success: [sofia/external/0817145779] Peer UUID: 74cac10a-77f4-4caa-a a17-66e9e3958567
2024-02-20 13:18:11.879985 96.53% [DEBUG] sofia.c:7493 Channel sofia/internal/10 1@pbx.example.co.id entering state [early][183]
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_ivr_bridge.c:1791 (sofia/extern al/0817145779) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_state_machine.c:581 (sofia /external/0817145779) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 8)
2024-02-20 13:18:11.879985 96.53% [DEBUG] switch_core_state_machine.c:650 (sofia /external/0817145779) State EXCHANGE_MEDIA
2024-02-20 13:18:11.879985 96.53% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA
2024-02-20 13:18:11.899984 96.53% [DEBUG] switch_rtp.c:7125 Correct audio RTCP i p/port confirmed.
2024-02-20 13:18:11.919984 96.53% [DEBUG] switch_rtp.c:1930 rtcp_stats_init: aud io ssrc[1035886213] base_seq[11010]
2024-02-20 13:18:11.919984 96.53% [DEBUG] switch_rtp.c:7695 Correct audio ip/por t confirmed.
2024-02-20 13:18:11.939985 96.53% [DEBUG] switch_rtp.c:7695 Correct audio ip/por t confirmed.
recv 687 bytes from udp/[43.245.185.3]:5060 at 13:18:15.774832:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 149.129.248.234:5090;rport=5090;received=149.129.248.234;branch =z9hG4bK7mNjUme9Q3U6p
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
From: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
To: <sip:0817145779@43.245.185.3:5060>;tag=b8565602-f3e2-4752-b490-8cb1f0e18fe6
CSeq: 79651153 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, OPTIONS
Contact: <sip:43.245.185.3:5060>
Content-Type: application/sdp
Content-Length: 191

v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2024-02-20 13:18:15.759984 96.47% [DEBUG] sofia.c:7493 Channel sofia/external/08 17145779 entering state [proceeding][180]
2024-02-20 13:18:15.759984 96.47% [DEBUG] sofia.c:7500 Duplicate SDP
v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2024-02-20 13:18:15.759984 96.47% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-02-20 13:18:15.759984 96.47% [DEBUG] switch_core_media.c:5588 Audio Codec C ompare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2024-02-20 13:18:15.759984 96.47% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-02-20 13:18:15.759984 96.47% [DEBUG] switch_core_media.c:5438 Set telephone -event payload to 101@8000
2024-02-20 13:18:15.759984 96.47% [DEBUG] switch_core_media.c:5798 Set telephone -event payload to 101@8000
2024-02-20 13:18:15.759984 96.47% [DEBUG] switch_core_media.c:5856 sofia/externa l/0817145779 Set 2833 dtmf send payload to 101 recv payload to 101
2024-02-20 13:18:15.759984 96.47% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/0817145779.
recv 687 bytes from udp/[43.245.185.3]:5060 at 13:18:17.179268:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 149.129.248.234:5090;rport=5090;received=149.129.248.234;branch =z9hG4bK7mNjUme9Q3U6p
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
From: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
To: <sip:0817145779@43.245.185.3:5060>;tag=b8565602-f3e2-4752-b490-8cb1f0e18fe6
CSeq: 79651153 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, OPTIONS
Contact: <sip:43.245.185.3:5060>
Content-Type: application/sdp
Content-Length: 191

v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2024-02-20 13:18:17.159983 96.43% [DEBUG] sofia.c:7493 Channel sofia/external/08 17145779 entering state [proceeding][180]
2024-02-20 13:18:17.159983 96.43% [DEBUG] sofia.c:7500 Duplicate SDP
v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2024-02-20 13:18:17.159983 96.43% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-02-20 13:18:17.159983 96.43% [DEBUG] switch_core_media.c:5588 Audio Codec C ompare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2024-02-20 13:18:17.159983 96.43% [DEBUG] switch_core_media.c:5526 Audio Codec C ompare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-02-20 13:18:17.159983 96.43% [DEBUG] switch_core_media.c:5438 Set telephone -event payload to 101@8000
2024-02-20 13:18:17.159983 96.43% [DEBUG] switch_core_media.c:5798 Set telephone -event payload to 101@8000
2024-02-20 13:18:17.159983 96.43% [DEBUG] switch_core_media.c:5856 sofia/externa l/0817145779 Set 2833 dtmf send payload to 101 recv payload to 101
2024-02-20 13:18:17.159983 96.43% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/0817145779.
recv 701 bytes from udp/[43.245.185.3]:5060 at 13:18:18.552736:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 149.129.248.234:5090;rport=5090;received=149.129.248.234;branch =z9hG4bK7mNjUme9Q3U6p
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
From: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
To: <sip:0817145779@43.245.185.3:5060>;tag=b8565602-f3e2-4752-b490-8cb1f0e18fe6
CSeq: 79651153 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, OPTIONS
Contact: <sip:43.245.185.3:5060>
Supported: 100rel
Content-Type: application/sdp
Content-Length: 191

v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2024-02-20 13:18:18.539985 97.57% [DEBUG] sofia.c:7493 Channel sofia/external/08 17145779 entering state [completing][200]
2024-02-20 13:18:18.539985 97.57% [DEBUG] sofia.c:7500 Duplicate SDP
v=0
o=GANG 748312476 748312477 IN IP4 43.245.185.3
s=-
c=IN IP4 43.245.185.3
t=0 0
m=audio 11010 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

send 494 bytes to udp/[43.245.185.3]:5060 at 13:18:18.560010:
------------------------------------------------------------------------
ACK sip:43.245.185.3:5060 SIP/2.0
Via: SIP/2.0/UDP 149.129.248.234:5090;rport;branch=z9hG4bK8XeBXFZcNcjSj
Max-Forwards: 70
From: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
To: <sip:0817145779@43.245.185.3:5060>;tag=b8565602-f3e2-4752-b490-8cb1f0e18fe6
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
CSeq: 79651153 ACK
Contact: <sip:gw+ad676e11-b979-48bc-b88f-de56da4a0a0e@149.129.248.234:5090;trans port=udp;gw=ad676e11-b979-48bc-b88f-de56da4a0a0e>
Content-Length: 0


2024-02-20 13:18:18.559986 97.57% [DEBUG] sofia.c:7493 Channel sofia/external/08 17145779 entering state [ready][200]
2024-02-20 13:18:18.559986 97.57% [NOTICE] sofia.c:8622 Channel [sofia/external/ 0817145779] has been answered
2024-02-20 13:18:18.559986 97.57% [DEBUG] switch_channel.c:3912 (sofia/external/ 0817145779) Callstate Change EARLY -> ACTIVE
2024-02-20 13:18:18.579987 97.57% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/internal/101@pbx.example.co.id.
2024-02-20 13:18:18.579987 97.57% [DEBUG] mod_sofia.c:914 Local SDP sofia/intern al/101@pbx.example.co.id:
v=0
o=FreeSWITCH 1708392833 1708392835 IN IP4 149.129.248.234
s=FreeSWITCH
c=IN IP4 149.129.248.234
t=0 0
m=audio 17058 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
a=rtcp:17059 IN IP4 149.129.248.234

2024-02-20 13:18:18.579987 97.57% [NOTICE] switch_ivr_bridge.c:730 Channel [sofi a/internal/101@pbx.example.co.id] has been answered
2024-02-20 13:18:18.579987 97.57% [DEBUG] switch_channel.c:3912 (sofia/internal/ 101@pbx.example.co.id) Callstate Change EARLY -> ACTIVE
2024-02-20 13:18:18.579987 97.57% [DEBUG] sofia.c:7493 Channel sofia/internal/10 1@pbx.example.co.id entering state [completed][200]
2024-02-20 13:18:18.599984 97.57% [DEBUG] sofia.c:7493 Channel sofia/internal/10 1@pbx.example.co.id entering state [ready][200]
2024-02-20 13:18:18.619984 97.57% [DEBUG] switch_rtp.c:7695 Correct audio ip/por t confirmed.
2024-02-20 13:18:18.619984 97.57% [DEBUG] switch_rtp.c:7695 Correct audio ip/por t confirmed.
recv 545 bytes from udp/[43.245.185.3]:5060 at 13:18:21.686594:
------------------------------------------------------------------------
BYE sip:gw+ad676e11-b979-48bc-b88f-de56da4a0a0e@149.129.248.234:5090;transport=u dp;gw=ad676e11-b979-48bc-b88f-de56da4a0a0e SIP/2.0
Via: SIP/2.0/UDP 43.245.185.3:5060;rport;branch=z9hG4bKPj88ef7fa6-300a-4e76-ae4a -0bad44048528
Max-Forwards: 70
From: <sip:0817145779@43.245.185.3:5060>;tag=b8565602-f3e2-4752-b490-8cb1f0e18fe 6
To: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
CSeq: 6941 BYE
User-Agent: IPSIP/2.8.0_1.6.2_2016.06.13
Reason: Q.850;cause=16
Content-Length: 0


2024-02-20 13:18:21.679986 98.53% [NOTICE] sofia.c:1065 Hangup sofia/external/08 17145779 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
send 510 bytes to udp/[43.245.185.3]:5060 at 13:18:21.699801:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 43.245.185.3:5060;rport=5060;branch=z9hG4bKPj88ef7fa6-300a-4e76 -ae4a-0bad44048528
From: <sip:0817145779@43.245.185.3:5060>;tag=b8565602-f3e2-4752-b490-8cb1f0e18fe 6
To: "101" <sip:622131180035@43.245.185.3:5060>;tag=7N3B1g7Z3cDrg
Call-ID: a9a88665-4a5a-123d-ff81-00163e038571
CSeq: 6941 BYE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER , NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
Content-Length: 0


2024-02-20 13:18:21.679986 98.53% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/external/0817145779]
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:650 (sofia /external/0817145779) State EXCHANGE_MEDIA going to sleep
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:581 (sofia /external/0817145779) Running State Change CS_HANGUP (Cur 2 Tot 8)
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:844 (sofia /external/0817145779) Callstate Change ACTIVE -> HANGUP
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:846 (sofia /external/0817145779) State HANGUP
2024-02-20 13:18:21.699987 98.53% [DEBUG] mod_sofia.c:469 Channel sofia/external /0817145779 hanging up, cause: NORMAL_CLEARING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:59 sofia/e xternal/0817145779 Standard HANGUP, cause: NORMAL_CLEARING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:846 (sofia /external/0817145779) State HANGUP going to sleep
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:616 (sofia /external/0817145779) State Change CS_HANGUP -> CS_REPORTING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:581 (sofia /external/0817145779) Running State Change CS_REPORTING (Cur 2 Tot 8)
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:932 (sofia /external/0817145779) State REPORTING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:168 sofia/ external/0817145779 Standard REPORTING, cause: NORMAL_CLEARING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:932 (sofia /external/0817145779) State REPORTING going to sleep
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:607 (sofia /external/0817145779) State Change CS_REPORTING -> CS_DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_session.c:1744 Session 8 ( sofia/external/0817145779) Locked, Waiting on external entities
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/internal/101@pbx.example.co.id]
2024-02-20 13:18:21.699987 98.53% [NOTICE] switch_ivr_bridge.c:1942 Hangup sofia /internal/101@pbx.example.co.id [CS_EXECUTE] [NORMAL_CLEARING]
2024-02-20 13:18:21.699987 98.53% [NOTICE] switch_core_session.c:1762 Session 8 (sofia/external/0817145779) Ended
2024-02-20 13:18:21.699987 98.53% [NOTICE] switch_core_session.c:1766 Close Chan nel sofia/external/0817145779 [CS_DESTROY]
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_session.c:2979 sofia/inter nal/101@pbx.example.co.id skip receive message [APPLICATION_EXEC_COMPLETE] (ch annel is hungup already)
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:647 (sofia /internal/101@pbx.example.co.id) State EXECUTE going to sleep
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:581 (sofia /internal/101@pbx.example.co.id) Running State Change CS_HANGUP (Cur 1 Tot 8)
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:735 (sofia /external/0817145779) Running State Change CS_DESTROY (Cur 1 Tot 8)
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:844 (sofia /internal/101@pbx.example.co.id) Callstate Change ACTIVE -> HANGUP
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:745 (sofia /external/0817145779) State DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] mod_sofia.c:380 sofia/external/0817145 779 SOFIA DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:175 sofia/ external/0817145779 Standard DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:745 (sofia /external/0817145779) State DESTROY going to sleep
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:846 (sofia /internal/101@pbx.example.co.id) State HANGUP
2024-02-20 13:18:21.699987 98.53% [DEBUG] mod_sofia.c:463 sofia/internal/101@pbx .example.co.id Overriding SIP cause 480 with 200 from the other leg
2024-02-20 13:18:21.699987 98.53% [DEBUG] mod_sofia.c:469 Channel sofia/internal /101@pbx.example.co.id hanging up, cause: NORMAL_CLEARING
2024-02-20 13:18:21.699987 98.53% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/i nternal/101@pbx.example.co.id
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:59 sofia/i nternal/101@pbx.example.co.id Standard HANGUP, cause: NORMAL_CLEARING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:846 (sofia /internal/101@pbx.example.co.id) State HANGUP going to sleep
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:616 (sofia /internal/101@pbx.example.co.id) State Change CS_HANGUP -> CS_REPORTING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:581 (sofia /internal/101@pbx.example.co.id) Running State Change CS_REPORTING (Cur 1 Tot 8)
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:932 (sofia /internal/101@pbx.example.co.id) State REPORTING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:168 sofia/ internal/101@pbx.example.co.id Standard REPORTING, cause: NORMAL_CLEARING
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:932 (sofia /internal/101@pbx.example.co.id) State REPORTING going to sleep
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:607 (sofia /internal/101@pbx.example.co.id) State Change CS_REPORTING -> CS_DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_session.c:1744 Session 7 ( sofia/internal/101@pbx.example.co.id) Locked, Waiting on external entities
2024-02-20 13:18:21.699987 98.53% [NOTICE] switch_core_session.c:1762 Session 7 (sofia/internal/101@pbx.example.co.id) Ended
2024-02-20 13:18:21.699987 98.53% [NOTICE] switch_core_session.c:1766 Close Chan nel sofia/internal/101@pbx.example.co.id [CS_DESTROY]
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:735 (sofia /internal/101@pbx.example.co.id) Running State Change CS_DESTROY (Cur 0 Tot 8)
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:745 (sofia /internal/101@pbx.example.co.id) State DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] mod_sofia.c:380 sofia/internal/101@pbx .example.co.id SOFIA DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:175 sofia/ internal/101@pbx.example.co.id Standard DESTROY
2024-02-20 13:18:21.699987 98.53% [DEBUG] switch_core_state_machine.c:745 (sofia /internal/101@pbx.example.co.id) State DESTROY going to sleep
freeswitch@pbx> sofia profile external siptrace off
Disabled sip debugging on external
Type control-D or /exit or /quit or /bye to exit.

freeswitch@pbx> /exit

root@pbx:~#
 
Last edited:
M

markjcrane

Active Member
Staff member
Jul 22, 2018
448
162
43
49
Is this a new install or an existing install that has been upgraded for a few years?
 
M

markjcrane

Active Member
Staff member
Jul 22, 2018
448
162
43
49
Oh, so you are trying to block out bound calls. If that is the case
  • Delete the old call block dialplan then run upgrade -> app defaults.
  • Confirm there is a new call block dialplan
  • Go to Status -> SIP Status and press the FLUSH CACHE button
  • Then try making a new call.
 
W

Win

Member
Feb 12, 2024
49
1
8
pls ignore this.. I have resolved this by putting the callee number to number without the country code and empty the name field, (just want to share to others)
thank you
 
Status
Not open for further replies.
Top Bottom