Multi tenant setup - calls not working between domains & outbound

Status
Not open for further replies.
A

apv04

New Member
Aug 13, 2024
5
0
1
32
Hi guys long time lurker, first time poster here.
I am hoping someone can help me with a problem I am experiencing all of a sudden with a FusionPBX instance. So I have two domains pbx.something.com and tenant1.pbx.something.com. I have SIP clients registered in both domains, inbound calls work in both domains, calls within domain work but calls between domains and calls out don't. This happened suddenly for the second time(the first time it resolved by itself).
I talked with my SIP trunk provider and they say that they aren't receiving any CID from the calls but it is set in the extension(when it was working I was able to call out with any number). Based on the SIP traces the error is 478.
I am happy to provide the any logs and any other info needed if someone can help me understand what I am doin't wrong.

Here is a log of a unsuccessfull call from extension 2000(public number 470 0100) to public number 888 1683
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.653103 99.33% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/2000@hotel701.pbx.austurljos.is [6cc22ac4-746c-4541-8196-b848de4bb5b2]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.653103 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/2000@hotel701.pbx.austurljos.is) Running State Change CS_NEW (Cur 1 Tot 53)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.653103 99.33% [INFO] sofia.c:10459 sofia/internal/2000@hotel701.pbx.austurljos.is receiving invite from 185.44.204.106:5931 version: 1.10.11 -release 64bit call-id: 3427538565@10.40.0.158
2024-08-20 15:37:48.653103 99.33% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 185.44.204.106:0.
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.673029 99.33% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/2000@hotel701.pbx.austurljos.is) State NEW
2024-08-20 15:37:48.673029 99.33% [DEBUG] sofia.c:2419 detaching session 6cc22ac4-746c-4541-8196-b848de4bb5b2
2024-08-20 15:37:48.753101 99.33% [DEBUG] sofia.c:2532 Re-attaching to session 6cc22ac4-746c-4541-8196-b848de4bb5b2
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [INFO] sofia.c:10459 sofia/internal/2000@hotel701.pbx.austurljos.is receiving invite from 185.44.204.106:5931 version: 1.10.11 -release 64bit call-id: 3427538565@10.40.0.158
2024-08-20 15:37:48.773042 99.33% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 185.44.204.106:0.
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] sofia.c:7493 Channel sofia/internal/2000@hotel701.pbx.austurljos.is entering state [received][100]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] sofia.c:7503 Remote SDP:
6cc22ac4-746c-4541-8196-b848de4bb5b2 v=0
6cc22ac4-746c-4541-8196-b848de4bb5b2 o=- 20079 20079 IN IP4 185.44.204.106
6cc22ac4-746c-4541-8196-b848de4bb5b2 s=SDP data
6cc22ac4-746c-4541-8196-b848de4bb5b2 c=IN IP4 185.44.204.106
6cc22ac4-746c-4541-8196-b848de4bb5b2 t=0 0
6cc22ac4-746c-4541-8196-b848de4bb5b2 m=audio 11788 RTP/AVP 9 0 8 18 101
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=rtpmap:9 G722/8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=rtpmap:0 PCMU/8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=rtpmap:8 PCMA/8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=rtpmap:18 G729/8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=fmtp:18 annexb=no
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=rtpmap:101 telephone-event/8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=fmtp:101 0-15
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=ptime:20
6cc22ac4-746c-4541-8196-b848de4bb5b2
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] sofia.c:7906 (sofia/internal/2000@hotel701.pbx.austurljos.is) State Change CS_NEW -> CS_INIT
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/2000@hotel701.pbx.austurljos.is) Running State Change CS_INIT (Cur 1 Tot 53)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/2000@hotel701.pbx.austurljos.is) State INIT
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] mod_sofia.c:97 sofia/internal/2000@hotel701.pbx.austurljos.is SOFIA INIT
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:40 sofia/internal/2000@hotel701.pbx.austurljos.is Standard INIT
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2000@hotel701.pbx.austurljos.is) State Change CS_INIT -> CS_ROUTING
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/2000@hotel701.pbx.austurljos.is) State INIT going to sleep
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/2000@hotel701.pbx.austurljos.is) Running State Change CS_ROUTING (Cur 1 Tot 53)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_channel.c:2399 (sofia/internal/2000@hotel701.pbx.austurljos.is) Callstate Change DOWN -> RINGING
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/2000@hotel701.pbx.austurljos.is) State ROUTING
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] mod_sofia.c:158 sofia/internal/2000@hotel701.pbx.austurljos.is SOFIA ROUTING
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [DEBUG] switch_core_state_machine.c:230 sofia/internal/2000@hotel701.pbx.austurljos.is Standard ROUTING
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.773042 99.33% [INFO] mod_dialplan_xml.c:639 Processing Lobby <2000>->8881683 in context hotel701.pbx.austurljos.is
2024-08-20 15:37:48.773042 99.33% [NOTICE] switch_cpp.cpp:1466 [xml_handler] multiple key:dialplan:hotel701.pbx.austurljos.is
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->user_exists] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_exists] () =~ // break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(user_exists=false)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.793066 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [user_exists]=[false]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(from_user_exists=true)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.793066 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [from_user_exists]=[true]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->caller-details] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(caller_destination=${destination_number}) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(caller_destination=8881683)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.793066 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [caller_destination]=[8881683]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(caller_id_name=${caller_id_name}) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(caller_id_name=2000)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.793066 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [caller_id_name]=[2000]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(caller_id_number=${caller_id_number})
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->global-variables] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [global-variables] () =~ // break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->domain-variables] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [domain-variables] () =~ // break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action export(origination_callee_id_name=${caller_destination})
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(operator=1000) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(operator=1000)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.793066 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [operator]=[1000]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->clear_sip_auto_answer] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->is_loopback] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->call-direction] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [call-direction] ${call_direction}() =~ /^$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action export(call_direction=local) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is export(call_direction=local)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.793066 99.33% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[local]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->call_block] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [call_block] ${call_direction}(local) =~ /^(inbound|outbound)$/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->user_record] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(from_user_record=)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [from_user_record]=[UNDEF]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->rtp_has_crypto] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is 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
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->redial] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [redial] destination_number(8881683) =~ /^(redial|\*870)$/ break=on-true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [redial] () =~ // break=never
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->speed_dial] continue=false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [speed_dial] destination_number(8881683) =~ /^\*0(.*)$/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->default_caller_id] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->user_hold_music] continue=true
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is parsing [hotel701.pbx.austurljos.is->Vodafone1.7d] continue=false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [Vodafone1.7d] ${user_exists}(false) =~ /false/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Regex (PASS) [Vodafone1.7d] destination_number(8881683) =~ /^(\d{7})$/ break=on-false
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action export(call_direction=outbound) INLINE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is export(call_direction=outbound)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[outbound]
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action unset(call_timeout)
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(hangup_after_bridge=true)
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(effective_caller_id_name=${outbound_caller_id_name})
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(effective_caller_id_number=${outbound_caller_id_number})
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(inherit_codec=true)
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(ignore_display_updates=true)
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action set(callee_id_number=8881683)
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is 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)
6cc22ac4-746c-4541-8196-b848de4bb5b2 Dialplan: sofia/internal/2000@hotel701.pbx.austurljos.is Action bridge(sofia/gateway/c1d58eb3-4b32-4170-8f81-83facef48d1d/8881683)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/2000@hotel701.pbx.austurljos.is) State Change CS_ROUTING -> CS_EXECUTE
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/2000@hotel701.pbx.austurljos.is) State ROUTING going to sleep
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/2000@hotel701.pbx.austurljos.is) Running State Change CS_EXECUTE (Cur 1 Tot 53)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/2000@hotel701.pbx.austurljos.is) State EXECUTE
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_sofia.c:213 sofia/internal/2000@hotel701.pbx.austurljos.is SOFIA EXECUTE
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:323 sofia/internal/2000@hotel701.pbx.austurljos.is Standard EXECUTE
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(caller_id_number=2000)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [caller_id_number]=[2000]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(RFC2822_DATE=Tue, 20 Aug 2024 15:37:48 +0000)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [RFC2822_DATE]=[Tue, 20 Aug 2024 15:37:48 +0000]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is export(origination_callee_id_name=8881683)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [origination_callee_id_name]=[8881683]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is hash(insert/hotel701.pbx.austurljos.is-last_dial/2000/8881683)
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is unset(call_timeout)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(hangup_after_bridge=true)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [hangup_after_bridge]=[true]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(effective_caller_id_name=)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [effective_caller_id_name]=[UNDEF]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(effective_caller_id_number=4700100)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [effective_caller_id_number]=[4700100]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(inherit_codec=true)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [inherit_codec]=[true]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(ignore_display_updates=true)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [ignore_display_updates]=[true]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is set(callee_id_number=8881683)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [callee_id_number]=[8881683]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is 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)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_dptools.c:1671 SET sofia/internal/2000@hotel701.pbx.austurljos.is [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]
6cc22ac4-746c-4541-8196-b848de4bb5b2 EXECUTE [depth=0] sofia/internal/2000@hotel701.pbx.austurljos.is bridge(sofia/gateway/c1d58eb3-4b32-4170-8f81-83facef48d1d/8881683)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_channel.c:1288 sofia/internal/2000@hotel701.pbx.austurljos.is EXPORTING[export_vars] [domain_name]=[hotel701.pbx.austurljos.is] to event
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_channel.c:1288 sofia/internal/2000@hotel701.pbx.austurljos.is EXPORTING[export_vars] [domain_uuid]=[df22ee58-3a5b-4a63-8b11-db216715bd82] to event
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_channel.c:1288 sofia/internal/2000@hotel701.pbx.austurljos.is EXPORTING[export_vars] [call_direction]=[outbound] to event
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_channel.c:1288 sofia/internal/2000@hotel701.pbx.austurljos.is EXPORTING[export_vars] [call_direction]=[outbound] to event
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_channel.c:1288 sofia/internal/2000@hotel701.pbx.austurljos.is EXPORTING[export_vars] [origination_callee_id_name]=[8881683] to event
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_ivr_originate.c:2301 Parsing global variables
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [NOTICE] switch_channel.c:1142 New Channel sofia/external/8881683 [d8ceced5-91e0-4d23-9498-2f046e21b89f]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_sofia.c:5110 (sofia/external/8881683) State Change CS_NEW -> CS_INIT
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/8881683) Running State Change CS_INIT (Cur 2 Tot 54)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:624 (sofia/external/8881683) State INIT
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_sofia.c:97 sofia/external/8881683 SOFIA INIT
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [INFO] sofia_glue.c:1659 sofia/external/8881683 sending invite call-id: (null)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] sofia_glue.c:1662 sofia/external/8881683 sending invite version: 1.10.11 -release 64bit
d8ceced5-91e0-4d23-9498-2f046e21b89f Local SDP:
d8ceced5-91e0-4d23-9498-2f046e21b89f v=0
d8ceced5-91e0-4d23-9498-2f046e21b89f o=FreeSWITCH 1724136262 1724136263 IN IP4 185.44.204.195
d8ceced5-91e0-4d23-9498-2f046e21b89f s=FreeSWITCH
d8ceced5-91e0-4d23-9498-2f046e21b89f c=IN IP4 185.44.204.195
d8ceced5-91e0-4d23-9498-2f046e21b89f t=0 0
d8ceced5-91e0-4d23-9498-2f046e21b89f m=audio 32006 RTP/AVP 9 0 8 101
d8ceced5-91e0-4d23-9498-2f046e21b89f a=rtpmap:9 G722/8000
d8ceced5-91e0-4d23-9498-2f046e21b89f a=rtpmap:0 PCMU/8000
d8ceced5-91e0-4d23-9498-2f046e21b89f a=rtpmap:8 PCMA/8000
d8ceced5-91e0-4d23-9498-2f046e21b89f a=rtpmap:101 telephone-event/8000
d8ceced5-91e0-4d23-9498-2f046e21b89f a=fmtp:101 0-15
d8ceced5-91e0-4d23-9498-2f046e21b89f a=silenceSupp:eek:ff - - - -
d8ceced5-91e0-4d23-9498-2f046e21b89f a=ptime:20
d8ceced5-91e0-4d23-9498-2f046e21b89f a=sendrecv
d8ceced5-91e0-4d23-9498-2f046e21b89f
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:40 sofia/external/8881683 Standard INIT
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:48 (sofia/external/8881683) State Change CS_INIT -> CS_ROUTING
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:624 (sofia/external/8881683) State INIT going to sleep
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/8881683) Running State Change CS_ROUTING (Cur 2 Tot 54)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:640 (sofia/external/8881683) State ROUTING
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] mod_sofia.c:158 sofia/external/8881683 SOFIA ROUTING
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_ivr_originate.c:67 (sofia/external/8881683) State Change CS_ROUTING -> CS_CONSUME_MEDIA
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:640 (sofia/external/8881683) State ROUTING going to sleep
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/8881683) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 54)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] sofia.c:7493 Channel sofia/external/8881683 entering state [calling][0]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:659 (sofia/external/8881683) State CONSUME_MEDIA
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:48.813045 99.33% [DEBUG] switch_core_state_machine.c:659 (sofia/external/8881683) State CONSUME_MEDIA going to sleep
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] sofia.c:7493 Channel sofia/external/8881683 entering state [proceeding][183]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] sofia.c:7503 Remote SDP:
d8ceced5-91e0-4d23-9498-2f046e21b89f v=0
d8ceced5-91e0-4d23-9498-2f046e21b89f o=anonymous 172416826732 172416826733 IN IP4 193.4.117.112
d8ceced5-91e0-4d23-9498-2f046e21b89f s=SIP Call
d8ceced5-91e0-4d23-9498-2f046e21b89f c=IN IP4 193.4.117.102
d8ceced5-91e0-4d23-9498-2f046e21b89f t=0 0
d8ceced5-91e0-4d23-9498-2f046e21b89f m=audio 33980 RTP/AVP 8 101
d8ceced5-91e0-4d23-9498-2f046e21b89f b=AS:80
d8ceced5-91e0-4d23-9498-2f046e21b89f a=rtpmap:8 PCMA/8000/1
d8ceced5-91e0-4d23-9498-2f046e21b89f a=rtpmap:101 telephone-event/8000
d8ceced5-91e0-4d23-9498-2f046e21b89f a=fmtp:101 0-15
d8ceced5-91e0-4d23-9498-2f046e21b89f a=ptime:20
d8ceced5-91e0-4d23-9498-2f046e21b89f
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [NOTICE] sofia.c:7506 Pre-Answer sofia/external/8881683!
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_channel.c:3585 (sofia/external/8881683) Callstate Change DOWN -> EARLY
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:3731 Set Codec sofia/external/8881683 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_codec.c:111 sofia/external/8881683 Original read codec set to PCMA:8
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:5856 sofia/external/8881683 Set 2833 dtmf send payload to 101 recv payload to 101
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:8660 AUDIO RTP [sofia/external/8881683] 185.44.204.195 port 32006 -> 193.4.117.102 port 33980 codec: 8 ms: 20
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_rtp.c:4563 Starting timer [soft] 160 bytes per 20ms
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:8973 sofia/external/8881683 Set 2833 dtmf send payload to 101
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:8980 sofia/external/8881683 Set 2833 dtmf receive payload to 101
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.653098 99.17% [DEBUG] switch_core_media.c:9003 sofia/external/8881683 Set rtp dtmf delay to 40
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_ivr_originate.c:455 Setting codec string on sofia/internal/2000@hotel701.pbx.austurljos.is to PCMA@8000h@20i
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [INFO] switch_ivr_originate.c:3862 Sending early media
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:3731 Set Codec sofia/internal/2000@hotel701.pbx.austurljos.is PCMA/8000 20 ms 160 samples 64000 bits 1 channels
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_codec.c:111 sofia/internal/2000@hotel701.pbx.austurljos.is Original read codec set to PCMA:8
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:5856 sofia/internal/2000@hotel701.pbx.austurljos.is Set 2833 dtmf send payload to 101 recv payload to 101
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:8660 AUDIO RTP [sofia/internal/2000@hotel701.pbx.austurljos.is] 185.44.204.195 port 29616 -> 185.44.204.106 port 11788 codec: 8 ms: 20
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_rtp.c:4563 Starting timer [soft] 160 bytes per 20ms
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:8973 sofia/internal/2000@hotel701.pbx.austurljos.is Set 2833 dtmf send payload to 101
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:8980 sofia/internal/2000@hotel701.pbx.austurljos.is Set 2833 dtmf receive payload to 101
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:9003 sofia/internal/2000@hotel701.pbx.austurljos.is Set rtp dtmf delay to 40
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [NOTICE] sofia_media.c:90 Pre-Answer sofia/internal/2000@hotel701.pbx.austurljos.is!
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_channel.c:3585 (sofia/internal/2000@hotel701.pbx.austurljos.is) Callstate Change RINGING -> EARLY
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/internal/2000@hotel701.pbx.austurljos.is.
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] mod_sofia.c:2607 Ring SDP:
6cc22ac4-746c-4541-8196-b848de4bb5b2 v=0
6cc22ac4-746c-4541-8196-b848de4bb5b2 o=FreeSWITCH 1724138653 1724138654 IN IP4 185.44.204.195
6cc22ac4-746c-4541-8196-b848de4bb5b2 s=FreeSWITCH
6cc22ac4-746c-4541-8196-b848de4bb5b2 c=IN IP4 185.44.204.195
6cc22ac4-746c-4541-8196-b848de4bb5b2 t=0 0
6cc22ac4-746c-4541-8196-b848de4bb5b2 m=audio 29616 RTP/AVP 8 101
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=rtpmap:8 PCMA/8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=rtpmap:101 telephone-event/8000
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=fmtp:101 0-15
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=ptime:20
6cc22ac4-746c-4541-8196-b848de4bb5b2 a=sendrecv
6cc22ac4-746c-4541-8196-b848de4bb5b2
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_ivr_originate.c:3913 Originate Resulted in Success: [sofia/external/8881683] Peer UUID: d8ceced5-91e0-4d23-9498-2f046e21b89f
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.673035 99.17% [DEBUG] sofia.c:7493 Channel sofia/internal/2000@hotel701.pbx.austurljos.is entering state [early][183]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_ivr_bridge.c:1791 (sofia/external/8881683) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_state_machine.c:581 (sofia/external/8881683) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 54)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.673035 99.17% [DEBUG] switch_core_state_machine.c:650 (sofia/external/8881683) State EXCHANGE_MEDIA
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.673035 99.17% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:49.853062 99.17% [DEBUG] switch_rtp.c:7695 Correct audio ip/port confirmed.
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:49.873072 99.17% [DEBUG] switch_rtp.c:7695 Correct audio ip/port confirmed.
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] sofia.c:7493 Channel sofia/internal/2000@hotel701.pbx.austurljos.is entering state [terminated][487]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [NOTICE] sofia.c:8735 Hangup sofia/internal/2000@hotel701.pbx.austurljos.is [CS_EXECUTE] [ORIGINATOR_CANCEL]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_ivr_bridge.c:825 sofia/internal/2000@hotel701.pbx.austurljos.is ending bridge by request from read function
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/internal/2000@hotel701.pbx.austurljos.is]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_ivr_bridge.c:819 sofia/internal/2000@hotel701.pbx.austurljos.is ending bridge by request from write function
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/external/8881683]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/external/8881683 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:650 (sofia/external/8881683) State EXCHANGE_MEDIA going to sleep
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/external/8881683) Running State Change CS_HANGUP (Cur 2 Tot 54)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:844 (sofia/external/8881683) Callstate Change EARLY -> HANGUP
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:846 (sofia/external/8881683) State HANGUP
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] mod_sofia.c:463 sofia/external/8881683 Overriding SIP cause 487 with 487 from the other leg
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] mod_sofia.c:469 Channel sofia/external/8881683 hanging up, cause: ORIGINATOR_CANCEL
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_ivr_bridge.c:1890 sofia/external/8881683 skip receive message [UNBRIDGE] (channel is hungup already)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_ivr_bridge.c:1893 sofia/internal/2000@hotel701.pbx.austurljos.is skip receive message [UNBRIDGE] (channel is hungup already)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/external/8881683
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:59 sofia/external/8881683 Standard HANGUP, cause: ORIGINATOR_CANCEL
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:846 (sofia/external/8881683) State HANGUP going to sleep
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:616 (sofia/external/8881683) State Change CS_HANGUP -> CS_REPORTING
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/external/8881683) Running State Change CS_REPORTING (Cur 2 Tot 54)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_session.c:2979 sofia/internal/2000@hotel701.pbx.austurljos.is skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/2000@hotel701.pbx.austurljos.is) State EXECUTE going to sleep
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/2000@hotel701.pbx.austurljos.is) Running State Change CS_HANGUP (Cur 2 Tot 54)
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:932 (sofia/external/8881683) State REPORTING
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:168 sofia/external/8881683 Standard REPORTING, cause: ORIGINATOR_CANCEL
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:932 (sofia/external/8881683) State REPORTING going to sleep
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/2000@hotel701.pbx.austurljos.is) Callstate Change EARLY -> HANGUP
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/2000@hotel701.pbx.austurljos.is) State HANGUP
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] mod_sofia.c:469 Channel sofia/internal/2000@hotel701.pbx.austurljos.is hanging up, cause: ORIGINATOR_CANCEL
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:607 (sofia/external/8881683) State Change CS_REPORTING -> CS_DESTROY
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_session.c:1744 Session 54 (sofia/external/8881683) Locked, Waiting on external entities
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [NOTICE] switch_core_session.c:1762 Session 54 (sofia/external/8881683) Ended
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [NOTICE] switch_core_session.c:1766 Close Channel sofia/external/8881683 [CS_DESTROY]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:59 sofia/internal/2000@hotel701.pbx.austurljos.is Standard HANGUP, cause: ORIGINATOR_CANCEL
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:735 (sofia/external/8881683) Running State Change CS_DESTROY (Cur 1 Tot 54)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/2000@hotel701.pbx.austurljos.is) State HANGUP going to sleep
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/2000@hotel701.pbx.austurljos.is) State Change CS_HANGUP -> CS_REPORTING
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:745 (sofia/external/8881683) State DESTROY
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] mod_sofia.c:380 sofia/external/8881683 SOFIA DESTROY
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:175 sofia/external/8881683 Standard DESTROY
d8ceced5-91e0-4d23-9498-2f046e21b89f 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:745 (sofia/external/8881683) State DESTROY going to sleep
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/2000@hotel701.pbx.austurljos.is) Running State Change CS_REPORTING (Cur 1 Tot 54)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.813040 99.10% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/2000@hotel701.pbx.austurljos.is) State REPORTING
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_state_machine.c:168 sofia/internal/2000@hotel701.pbx.austurljos.is Standard REPORTING, cause: ORIGINATOR_CANCEL
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/2000@hotel701.pbx.austurljos.is) State REPORTING going to sleep
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/2000@hotel701.pbx.austurljos.is) State Change CS_REPORTING -> CS_DESTROY
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_session.c:1744 Session 53 (sofia/internal/2000@hotel701.pbx.austurljos.is) Locked, Waiting on external entities
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [NOTICE] switch_core_session.c:1762 Session 53 (sofia/internal/2000@hotel701.pbx.austurljos.is) Ended
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/2000@hotel701.pbx.austurljos.is [CS_DESTROY]
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/2000@hotel701.pbx.austurljos.is) Running State Change CS_DESTROY (Cur 0 Tot 54)
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/2000@hotel701.pbx.austurljos.is) State DESTROY
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] mod_sofia.c:380 sofia/internal/2000@hotel701.pbx.austurljos.is SOFIA DESTROY
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_state_machine.c:175 sofia/internal/2000@hotel701.pbx.austurljos.is Standard DESTROY
6cc22ac4-746c-4541-8196-b848de4bb5b2 2024-08-20 15:37:50.833030 99.10% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/2000@hotel701.pbx.austurljos.is) State DESTROY going to sleep
 
A

apv04

New Member
Aug 13, 2024
5
0
1
32
A little bit of a update. My outbound calls are working if I set the From User to a number but then all calls are showing as that number. It seems like the system is not allocating the numbers from the extension Outbound Caller ID Number
The outbound rules are default(I have deleted them and recreated them a few times)
 
A

apv04

New Member
Aug 13, 2024
5
0
1
32
In the txt file is a sofia global sip trace of a unsuccessfull call. the call ends with originator cancelled. I spoke with the SIP trunk provider again regarding this and they say that they just recive a cancel from my pbx. I did a reinstall of the fusionpbx all so on debian 12 (originally it was on ubuntu 20.04 lts) but the issue is exactly the same.
 

Attachments

  • trace 1.txt
    55 KB · Views: 0
A

apv04

New Member
Aug 13, 2024
5
0
1
32
Here is allso a sngrep in txt and two pcaps one for working SIP calls and one with calls beeing dropped because of the SIP 487 originator cancelled. Both calls use CID 4700100 the only difference is that in the working it is in the from user filed in the gateway in the not working that field is empty.
 

Attachments

  • sngrep.txt
    65.1 KB · Views: 0
  • pcaps.zip
    26.8 KB · Views: 0
Last edited:
M

markjcrane

Active Member
Staff member
Jul 22, 2018
499
177
43
49
By default calls between inbound destinations would send the call to your outbound routes and your provider would send it back to your server. This scenario is not ideal. To get around this issue I created a dialplan called is_local its disabled by default. If you enable ti, then it will do a lookup to see if the number is local; if it is local, it will route the call to the correct domain. It helps to fill in both the destination number and the country code prefix. Make sure not to include the + in the numbers if the country code it provided we don't need the plus.
 
Status
Not open for further replies.
Top Bottom