Incoming and Outgoing calls faill

pacolopz

New Member
May 18, 2023
9
0
1
36
Hello all, I have a fresh install of fusionpbx but I'm having problems with incoming and outgoing calls going trough my carrier this is part of the trace that I have, maybe can someone provide light


Code:
SIP/2.0 408 Request Timeout
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPj875cf251a07f4718a0047d318bf41f03
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>;tag=6HUag70U043Sp
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25646 INVITE
User-Agent: FreeSWITCH
Accept: application/sdp
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Reason: Q.850;cause=102;text="RECOVERY_ON_TIMER_EXPIRE"
Content-Length: 0
Remote-Party-ID: "71576677" <sip:71576677@10.0.9.237>;party=calling;privacy=off;screen=no
 

pacolopz

New Member
May 18, 2023
9
0
1
36
Code:
recv 997 bytes from udp/[10.0.224.75]:59213 at 12:26:16.570595:
------------------------------------------------------------------------
INVITE sip:971576677@10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPj52a67483d3c5427dbfaa9e237aabd102
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>
Contact: "1001" <sip:1001@10.0.224.75:59213;ob>
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25645 INVITE
Route: <sip:10.0.9.237;lr>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.3
Content-Type: application/sdp
Content-Length:   336


v=0
o=- 3928915576 3928915576 IN IP4 10.0.224.75
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4058 RTP/AVP 0 8 101
c=IN IP4 10.0.224.75
b=TIAS:64000
a=rtcp:4059 IN IP4 10.0.224.75
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:261246850 cname:16854bea64b03a6d


2024-07-02 12:26:16.556859 98.63% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/1001@10.0.9.237 [469dadd0-215d-43eb-b784-15a88f5e1ac2]
2024-07-02 12:26:16.556859 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@10.0.9.237) Running State Change CS_NEW (Cur 1 Tot 1)
2024-07-02 12:26:16.556859 98.63% [INFO] sofia.c:10459 sofia/internal/1001@10.0.9.237 receiving invite from 10.0.224.75:59213 version: 1.10.11 -release 64bit call-id: d313dae897e04b13b4f1cbfc431f9f9d
2024-07-02 12:26:16.556859 98.63% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 10.0.224.75:0.
2024-07-02 12:26:16.556859 98.63% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/1001@10.0.9.237) State NEW
send 824 bytes to udp/[10.0.224.75]:59213 at 12:26:16.574626:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPj52a67483d3c5427dbfaa9e237aabd102
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>;tag=581Hecgr3UD7a
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25645 INVITE
User-Agent: FreeSWITCH
Accept: application/sdp
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Proxy-Authenticate: Digest realm="10.0.9.237", nonce="b37f1394-2b5b-43fc-a507-905dd1b726b0", algorithm=MD5, qop="auth"
Content-Length: 0




2024-07-02 12:26:16.556859 98.63% [DEBUG] sofia.c:2419 detaching session 469dadd0-215d-43eb-b784-15a88f5e1ac2
recv 380 bytes from udp/[10.0.224.75]:59213 at 12:26:16.576065:
------------------------------------------------------------------------
ACK sip:971576677@10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPj52a67483d3c5427dbfaa9e237aabd102
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>;tag=581Hecgr3UD7a
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25645 ACK
Route: <sip:10.0.9.237;lr>
Content-Length:  0




recv 1266 bytes from udp/[10.0.224.75]:59213 at 12:26:16.576167:
------------------------------------------------------------------------
INVITE sip:971576677@10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPj875cf251a07f4718a0047d318bf41f03
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>
Contact: "1001" <sip:1001@10.0.224.75:59213;ob>
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25646 INVITE
Route: <sip:10.0.9.237;lr>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: MicroSIP/3.21.3
Proxy-Authorization: Digest username="1001", realm="10.0.9.237", nonce="b37f1394-2b5b-43fc-a507-905dd1b726b0", uri="sip:971576677@10.0.9.237", response="d571bb88890252b3b39ad0c66d2e6202", algorithm=MD5, cnonce="b0b165e8af454ffabfbbb45fc0da3910", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Length:   336


v=0
o=- 3928915576 3928915576 IN IP4 10.0.224.75
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4058 RTP/AVP 0 8 101
c=IN IP4 10.0.224.75
b=TIAS:64000
a=rtcp:4059 IN IP4 10.0.224.75
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:261246850 cname:16854bea64b03a6d


2024-07-02 12:26:16.576221 98.63% [DEBUG] sofia.c:2532 Re-attaching to session 469dadd0-215d-43eb-b784-15a88f5e1ac2
2024-07-02 12:26:16.576221 98.63% [INFO] sofia.c:10459 sofia/internal/1001@10.0.9.237 receiving invite from 10.0.224.75:59213 version: 1.10.11 -release 64bit call-id: d313dae897e04b13b4f1cbfc431f9f9d
2024-07-02 12:26:16.576221 98.63% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 10.0.224.75:0.
2024-07-02 12:26:16.596238 98.63% [DEBUG] sofia.c:7493 Channel sofia/internal/1001@10.0.9.237 entering state [received][100]
2024-07-02 12:26:16.596238 98.63% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=- 3928915576 3928915576 IN IP4 10.0.224.75
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4058 RTP/AVP 0 8 101
c=IN IP4 10.0.224.75
b=TIAS:64000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtcp:4059 IN IP4 10.0.224.75
a=ssrc:261246850 cname:16854bea64b03a6d


2024-07-02 12:26:16.596238 98.63% [DEBUG] sofia.c:7906 (sofia/internal/1001@10.0.9.237) State Change CS_NEW -> CS_INIT
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@10.0.9.237) Running State Change CS_INIT (Cur 1 Tot 1)
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1001@10.0.9.237) State INIT
2024-07-02 12:26:16.596238 98.63% [DEBUG] mod_sofia.c:97 sofia/internal/1001@10.0.9.237 SOFIA INIT
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@10.0.9.237 Standard INIT
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@10.0.9.237) State Change CS_INIT -> CS_ROUTING
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1001@10.0.9.237) State INIT going to sleep
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@10.0.9.237) Running State Change CS_ROUTING (Cur 1 Tot 1)
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_channel.c:2399 (sofia/internal/1001@10.0.9.237) Callstate Change DOWN -> RINGING
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@10.0.9.237) State ROUTING
2024-07-02 12:26:16.596238 98.63% [DEBUG] mod_sofia.c:158 sofia/internal/1001@10.0.9.237 SOFIA ROUTING
2024-07-02 12:26:16.596238 98.63% [DEBUG] switch_core_state_machine.c:230 sofia/internal/1001@10.0.9.237 Standard ROUTING
2024-07-02 12:26:16.596238 98.63% [INFO] mod_dialplan_xml.c:639 Processing 1001 <1001>->971576677 in context 10.0.9.237
send 330 bytes to udp/[10.0.224.75]:59213 at 12:26:16.605454:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPj875cf251a07f4718a0047d318bf41f03
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25646 INVITE
User-Agent: FreeSWITCH
Content-Length: 0




2024-07-02 12:26:16.596238 98.63% [NOTICE] switch_cpp.cpp:1466 [xml_handler] multiple key:dialplan:10.0.9.237
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->user_exists] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(user_exists=false)
2024-07-02 12:26:16.616523 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [user_exists]=[false]
Dialplan: sofia/internal/1001@10.0.9.237 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(from_user_exists=true)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [from_user_exists]=[true]
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->caller-details] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Action set(caller_destination=${destination_number}) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(caller_destination=971576677)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [caller_destination]=[971576677]
Dialplan: sofia/internal/1001@10.0.9.237 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(caller_id_name=1001)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [caller_id_name]=[1001]
Dialplan: sofia/internal/1001@10.0.9.237 Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->global-variables] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->domain-variables] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [domain-variables] () =~ // break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 Action export(origination_callee_id_name=${caller_destination})
Dialplan: sofia/internal/1001@10.0.9.237 Action set(operator=1000) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(operator=1000)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [operator]=[1000]
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->clear_sip_auto_answer] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->is_loopback] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->call-direction] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [call-direction] ${call_direction}() =~ /^$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Action export(call_direction=local) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 export(call_direction=local)
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[local]
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->user_record] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(from_user_record=)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [from_user_record]=[UNDEF]
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->rtp_has_crypto] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 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
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->redial] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [redial] destination_number(971576677) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/1001@10.0.9.237 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->speed_dial] continue=false
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [speed_dial] destination_number(971576677) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->user_hold_music] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->default_caller_id] continue=true
Dialplan: sofia/internal/1001@10.0.9.237 Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 parsing [10.0.9.237->Tigo.9d8800d70800d71d3] continue=false
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [Tigo.9d8800d70800d71d3] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 Regex (PASS) [Tigo.9d8800d70800d71d3] destination_number(971576677) =~ /^9((?:\d{8})|(?:800\d{7})|(?:0800\d{7})|(?:1\d{3}))$/ break=on-false
Dialplan: sofia/internal/1001@10.0.9.237 Action export(call_direction=outbound) INLINE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 export(call_direction=outbound)
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[outbound]
Dialplan: sofia/internal/1001@10.0.9.237 Action unset(call_timeout)
Dialplan: sofia/internal/1001@10.0.9.237 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/1001@10.0.9.237 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/1001@10.0.9.237 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/1001@10.0.9.237 Action set(inherit_codec=true)
Dialplan: sofia/internal/1001@10.0.9.237 Action set(ignore_display_updates=true)
Dialplan: sofia/internal/1001@10.0.9.237 Action set(callee_id_number=71576677)
Dialplan: sofia/internal/1001@10.0.9.237 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/1001@10.0.9.237 Action set(outbound_prefix=9)
Dialplan: sofia/internal/1001@10.0.9.237 Action bridge(sofia/gateway/2f2a2db6-ceed-44c9-9fe7-57f7e601f019/71576677)
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/1001@10.0.9.237) State Change CS_ROUTING -> CS_EXECUTE
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@10.0.9.237) State ROUTING going to sleep
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@10.0.9.237) Running State Change CS_EXECUTE (Cur 1 Tot 1)
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/1001@10.0.9.237) State EXECUTE
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_sofia.c:213 sofia/internal/1001@10.0.9.237 SOFIA EXECUTE
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_core_state_machine.c:323 sofia/internal/1001@10.0.9.237 Standard EXECUTE
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(caller_id_number=1001)
2024-07-02 12:26:16.636229 98.63% [CONSOLE] sofia_presence.c:1621 Event Thread Started
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [caller_id_number]=[1001]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(RFC2822_DATE=Tue, 02 Jul 2024 12:26:16 -0700)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [RFC2822_DATE]=[Tue, 02 Jul 2024 12:26:16 -0700]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 export(origination_callee_id_name=971576677)
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [origination_callee_id_name]=[971576677]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 hash(insert/10.0.9.237-last_dial/1001/971576677)
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 unset(call_timeout)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(hangup_after_bridge=true)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(effective_caller_id_name=Colmena)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [effective_caller_id_name]=[Colmena]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(effective_caller_id_number=40317270)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [effective_caller_id_number]=[40317270]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(inherit_codec=true)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [inherit_codec]=[true]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(ignore_display_updates=true)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [ignore_display_updates]=[true]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(callee_id_number=71576677)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [callee_id_number]=[71576677]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 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-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [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]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 set(outbound_prefix=9)
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@10.0.9.237 [outbound_prefix]=[9]
EXECUTE [depth=0] sofia/internal/1001@10.0.9.237 bridge(sofia/gateway/2f2a2db6-ceed-44c9-9fe7-57f7e601f019/71576677)
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1288 sofia/internal/1001@10.0.9.237 EXPORTING[export_vars] [domain_name]=[10.0.9.237] to event
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1288 sofia/internal/1001@10.0.9.237 EXPORTING[export_vars] [domain_uuid]=[5d508018-925f-4e10-8fc8-e224173a6751] to event
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1288 sofia/internal/1001@10.0.9.237 EXPORTING[export_vars] [call_direction]=[outbound] to event
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1288 sofia/internal/1001@10.0.9.237 EXPORTING[export_vars] [call_direction]=[outbound] to event
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_channel.c:1288 sofia/internal/1001@10.0.9.237 EXPORTING[export_vars] [origination_callee_id_name]=[971576677] to event
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_ivr_originate.c:2301 Parsing global variables
2024-07-02 12:26:16.636229 98.63% [NOTICE] switch_channel.c:1142 New Channel sofia/external/71576677 [30427e68-36fd-4e0b-8b7e-453110f0aff7]
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_sofia.c:5110 (sofia/external/71576677) State Change CS_NEW -> CS_INIT
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/external/71576677) Running State Change CS_INIT (Cur 2 Tot 2)
2024-07-02 12:26:16.636229 98.63% [DEBUG] switch_core_state_machine.c:624 (sofia/external/71576677) State INIT
2024-07-02 12:26:16.636229 98.63% [DEBUG] mod_sofia.c:97 sofia/external/71576677 SOFIA INIT
2024-07-02 12:26:16.636229 98.63% [INFO] sofia_glue.c:1659 sofia/external/71576677 sending invite call-id: (null)
2024-07-02 12:26:16.636229 98.63% [DEBUG] sofia_glue.c:1662 sofia/external/71576677 sending invite version: 1.10.11 -release 64bit
Local SDP:
v=0
o=FreeSWITCH 1719929288 1719929289 IN IP4 190.X.X.X
s=FreeSWITCH
c=IN IP4 190.X.X.X
t=0 0
m=audio 19088 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv


send 1246 bytes to udp/[10.12.X.X]:5060 at 12:26:16.654824:
------------------------------------------------------------------------
INVITE sip:71576677@10.12.X.X SIP/2.0
Via: SIP/2.0/UDP 190.X.X.X:5080;rport;branch=z9hG4bK7pXgSNNBUetFj
Max-Forwards: 69
From: "Colmena" <sip:40317270@10.12.12.118>;tag=7jvvUj7Q4y3gr
To: <sip:71576677@10.12.X.X>
Call-ID: cb191095-b34b-123d-a3ac-005056bd9165
CSeq: 85420396 INVITE
Contact: <sip:gw+2f2a2db6-ceed-44c9-9fe7-57f7e601f019@190.X.X.X:5080;transport=udp;gw=2f2a2db6-ceed-44c9-9fe7-57f7e601f019>
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 273
X-FS-Support: update_display,send_info
Remote-Party-ID: "Colmena" <sip:40317270@10.12.12.118>;party=calling;screen=yes;privacy=off


v=0
o=FreeSWITCH 1719929288 1719929289 IN IP4 190.X.X.X
s=FreeSWITCH
c=IN IP4 190.X.X.X
t=0 0
m=audio 19088 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20


2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:40 sofia/external/71576677 Standard INIT
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:48 (sofia/external/71576677) State Change CS_INIT -> CS_ROUTING
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:624 (sofia/external/71576677) State INIT going to sleep
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/external/71576677) Running State Change CS_ROUTING (Cur 2 Tot 2)
2024-07-02 12:26:16.667255 98.63% [DEBUG] sofia.c:7493 Channel sofia/external/71576677 entering state [calling][0]
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:640 (sofia/external/71576677) State ROUTING
2024-07-02 12:26:16.667255 98.63% [DEBUG] mod_sofia.c:158 sofia/external/71576677 SOFIA ROUTING
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_ivr_originate.c:67 (sofia/external/71576677) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:640 (sofia/external/71576677) State ROUTING going to sleep
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/external/71576677) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 2)
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:659 (sofia/external/71576677) State CONSUME_MEDIA
2024-07-02 12:26:16.667255 98.63% [DEBUG] switch_core_state_machine.c:659 (sofia/external/71576677) State CONSUME_MEDIA going to sleep
send 1246 bytes to udp/[10.12.X.X]:5060 at 12:26:17.655010:
------------------------------------------------------------------------
INVITE sip:71576677@10.12.X.X SIP/2.0
Via: SIP/2.0/UDP 190.X.X.X:5080;rport;branch=z9hG4bK7pXgSNNBUetFj
Max-Forwards: 69
From: "Colmena" <sip:40317270@10.12.12.118>;tag=7jvvUj7Q4y3gr
To: <sip:71576677@10.12.X.X>
Call-ID: cb191095-b34b-123d-a3ac-005056bd9165
CSeq: 85420396 INVITE
Contact: <sip:gw+2f2a2db6-ceed-44c9-9fe7-57f7e601f019@190.X.X.X:5080;transport=udp;gw=2f2a2db6-ceed-44c9-9fe7-57f7e601f019>
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 273
X-FS-Support: update_display,send_info
Remote-Party-ID: "Colmena" <sip:40317270@10.12.12.118>;party=calling;screen=yes;privacy=off


v=0
o=FreeSWITCH 1719929288 1719929289 IN IP4 190.X.X.X
s=FreeSWITCH
c=IN IP4 190.X.X.X
t=0 0
m=audio 19088 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20


recv 554 bytes from udp/[10.0.224.75]:59213 at 12:26:19.334827:
------------------------------------------------------------------------
REGISTER sip:10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPj078d60f563e047caaf20981ad4c68075
Route: <sip:10.0.9.237;lr>
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=3594896f34ee455f8825de37b2f76238
To: "1001" <sip:1001@10.0.9.237>
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37837 REGISTER
User-Agent: MicroSIP/3.21.3
Contact: "1001" <sip:1001@10.0.224.75:59213;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length:  0




send 613 bytes to udp/[10.0.224.75]:59213 at 12:26:19.336048:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPj078d60f563e047caaf20981ad4c68075
From: "1001" <sip:1001@10.0.9.237>;tag=3594896f34ee455f8825de37b2f76238
To: "1001" <sip:1001@10.0.9.237>;tag=7tm3H2HZXDtcj
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37837 REGISTER
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
WWW-Authenticate: Digest realm="10.0.9.237", nonce="83d806f6-4113-4d18-a66e-9d7d5d122030", algorithm=MD5, qop="auth"
Content-Length: 0




recv 807 bytes from udp/[10.0.224.75]:59213 at 12:26:19.337280:
------------------------------------------------------------------------
REGISTER sip:10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPj4dc255502f944f76aab8558934710c27
Route: <sip:10.0.9.237;lr>
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=3594896f34ee455f8825de37b2f76238
To: "1001" <sip:1001@10.0.9.237>
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37838 REGISTER
User-Agent: MicroSIP/3.21.3
Contact: "1001" <sip:1001@10.0.224.75:59213;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="1001", realm="10.0.9.237", nonce="83d806f6-4113-4d18-a66e-9d7d5d122030", uri="sip:10.0.9.237", response="486c9e47ff3b38cd1dcfdcff92683d6b", algorithm=MD5, cnonce="a48ede39796244eb9a0fbd76d9088f84", qop=auth, nc=00000001
Content-Length:  0




send 576 bytes to udp/[10.0.224.75]:59213 at 12:26:19.342317:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPj4dc255502f944f76aab8558934710c27
From: "1001" <sip:1001@10.0.9.237>;tag=3594896f34ee455f8825de37b2f76238
To: "1001" <sip:1001@10.0.9.237>;tag=83DvKX22tpgZD
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37838 REGISTER
Contact: <sip:1001@10.0.224.75:59213;ob>;expires=300
Date: Tue, 02 Jul 2024 19:26:19 GMT
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
Content-Length: 0




send 1246 bytes to udp/[10.12.X.X]:5060 at 12:26:19.655013:
------------------------------------------------------------------------
INVITE sip:71576677@10.12.X.X SIP/2.0
Via: SIP/2.0/UDP 190.X.X.X:5080;rport;branch=z9hG4bK7pXgSNNBUetFj
Max-Forwards: 69
From: "Colmena" <sip:40317270@10.12.12.118>;tag=7jvvUj7Q4y3gr
To: <sip:71576677@10.12.X.X>
Call-ID: cb191095-b34b-123d-a3ac-005056bd9165
CSeq: 85420396 INVITE
Contact: <sip:gw+2f2a2db6-ceed-44c9-9fe7-57f7e601f019@190.X.X.X:5080;transport=udp;gw=2f2a2db6-ceed-44c9-9fe7-57f7e601f019>
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 273
X-FS-Support: update_display,send_info
Remote-Party-ID: "Colmena" <sip:40317270@10.12.12.118>;party=calling;screen=yes;privacy=off


v=0
o=FreeSWITCH 1719929288 1719929289 IN IP4 190.X.X.X
s=FreeSWITCH
c=IN IP4 190.X.X.X
t=0 0
m=audio 19088 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20


send 1246 bytes to udp/[10.12.X.X]:5060 at 12:26:23.655004:
------------------------------------------------------------------------
INVITE sip:71576677@10.12.X.X SIP/2.0
Via: SIP/2.0/UDP 190.X.X.X:5080;rport;branch=z9hG4bK7pXgSNNBUetFj
Max-Forwards: 69
From: "Colmena" <sip:40317270@10.12.12.118>;tag=7jvvUj7Q4y3gr
To: <sip:71576677@10.12.X.X>
Call-ID: cb191095-b34b-123d-a3ac-005056bd9165
CSeq: 85420396 INVITE
Contact: <sip:gw+2f2a2db6-ceed-44c9-9fe7-57f7e601f019@190.X.X.X:5080;transport=udp;gw=2f2a2db6-ceed-44c9-9fe7-57f7e601f019>
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 273
X-FS-Support: update_display,send_info
Remote-Party-ID: "Colmena" <sip:40317270@10.12.12.118>;party=calling;screen=yes;privacy=off


v=0
o=FreeSWITCH 1719929288 1719929289 IN IP4 190.X.X.X
s=FreeSWITCH
c=IN IP4 190.X.X.X
t=0 0
m=audio 19088 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20


send 1246 bytes to udp/[10.12.X.X]:5060 at 12:26:31.655010:
------------------------------------------------------------------------
INVITE sip:71576677@10.12.X.X SIP/2.0
Via: SIP/2.0/UDP 190.X.X.X:5080;rport;branch=z9hG4bK7pXgSNNBUetFj
Max-Forwards: 69
From: "Colmena" <sip:40317270@10.12.12.118>;tag=7jvvUj7Q4y3gr
To: <sip:71576677@10.12.X.X>
Call-ID: cb191095-b34b-123d-a3ac-005056bd9165
CSeq: 85420396 INVITE
Contact: <sip:gw+2f2a2db6-ceed-44c9-9fe7-57f7e601f019@190.X.X.X:5080;transport=udp;gw=2f2a2db6-ceed-44c9-9fe7-57f7e601f019>
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 273
X-FS-Support: update_display,send_info
Remote-Party-ID: "Colmena" <sip:40317270@10.12.12.118>;party=calling;screen=yes;privacy=off


v=0
o=FreeSWITCH 1719929288 1719929289 IN IP4 190.X.X.X
s=FreeSWITCH
c=IN IP4 190.X.X.X
t=0 0
m=audio 19088 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20


send 1246 bytes to udp/[10.12.X.X]:5060 at 12:26:47.655010:
------------------------------------------------------------------------
INVITE sip:71576677@10.12.X.X SIP/2.0
Via: SIP/2.0/UDP 190.X.X.X:5080;rport;branch=z9hG4bK7pXgSNNBUetFj
Max-Forwards: 69
From: "Colmena" <sip:40317270@10.12.12.118>;tag=7jvvUj7Q4y3gr
To: <sip:71576677@10.12.X.X>
Call-ID: cb191095-b34b-123d-a3ac-005056bd9165
CSeq: 85420396 INVITE
Contact: <sip:gw+2f2a2db6-ceed-44c9-9fe7-57f7e601f019@190.X.X.X:5080;transport=udp;gw=2f2a2db6-ceed-44c9-9fe7-57f7e601f019>
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 273
X-FS-Support: update_display,send_info
Remote-Party-ID: "Colmena" <sip:40317270@10.12.12.118>;party=calling;screen=yes;privacy=off


v=0
o=FreeSWITCH 1719929288 1719929289 IN IP4 190.X.X.X
s=FreeSWITCH
c=IN IP4 190.X.X.X
t=0 0
m=audio 19088 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20


2024-07-02 12:26:48.636239 99.33% [DEBUG] sofia.c:7493 Channel sofia/external/71576677 entering state [terminated][408]
2024-07-02 12:26:48.636239 99.33% [NOTICE] sofia.c:8735 Hangup sofia/external/71576677 [CS_CONSUME_MEDIA] [RECOVERY_ON_TIMER_EXPIRE]
2024-07-02 12:26:48.636239 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/71576677) Running State Change CS_HANGUP (Cur 2 Tot 2)
2024-07-02 12:26:48.636239 99.33% [DEBUG] switch_core_state_machine.c:844 (sofia/external/71576677) Callstate Change DOWN -> HANGUP
2024-07-02 12:26:48.636239 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/external/71576677) State HANGUP
2024-07-02 12:26:48.656224 99.33% [DEBUG] mod_sofia.c:469 Channel sofia/external/71576677 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:59 sofia/external/71576677 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/external/71576677) State HANGUP going to sleep
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:616 (sofia/external/71576677) State Change CS_HANGUP -> CS_REPORTING
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/71576677) Running State Change CS_REPORTING (Cur 2 Tot 2)
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/external/71576677) State REPORTING
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:168 sofia/external/71576677 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/external/71576677) State REPORTING going to sleep
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:607 (sofia/external/71576677) State Change CS_REPORTING -> CS_DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_session.c:1744 Session 2 (sofia/external/71576677) Locked, Waiting on external entities
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_ivr_originate.c:4056 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE]
2024-07-02 12:26:48.656224 99.33% [NOTICE] switch_core_session.c:1762 Session 2 (sofia/external/71576677) Ended
2024-07-02 12:26:48.656224 99.33% [NOTICE] switch_core_session.c:1766 Close Channel sofia/external/71576677 [CS_DESTROY]
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:735 (sofia/external/71576677) Running State Change CS_DESTROY (Cur 1 Tot 2)
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/external/71576677) State DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] mod_sofia.c:380 sofia/external/71576677 SOFIA DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:175 sofia/external/71576677 Standard DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/external/71576677) State DESTROY going to sleep
2024-07-02 12:26:48.656224 99.33% [INFO] mod_dptools.c:3635 Originate Failed.  Cause: RECOVERY_ON_TIMER_EXPIRE
2024-07-02 12:26:48.656224 99.33% [NOTICE] switch_channel.c:5012 Hangup sofia/internal/1001@10.0.9.237 [CS_EXECUTE] [RECOVERY_ON_TIMER_EXPIRE]
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_session.c:2979 sofia/internal/1001@10.0.9.237 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/1001@10.0.9.237) State EXECUTE going to sleep
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@10.0.9.237) Running State Change CS_HANGUP (Cur 1 Tot 2)
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/1001@10.0.9.237) Callstate Change RINGING -> HANGUP
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1001@10.0.9.237) State HANGUP
2024-07-02 12:26:48.656224 99.33% [DEBUG] mod_sofia.c:463 sofia/internal/1001@10.0.9.237 Overriding SIP cause 504 with 408 from the other leg
2024-07-02 12:26:48.656224 99.33% [DEBUG] mod_sofia.c:469 Channel sofia/internal/1001@10.0.9.237 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE
2024-07-02 12:26:48.656224 99.33% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 408
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:59 sofia/internal/1001@10.0.9.237 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1001@10.0.9.237) State HANGUP going to sleep
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/1001@10.0.9.237) State Change CS_HANGUP -> CS_REPORTING
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@10.0.9.237) Running State Change CS_REPORTING (Cur 1 Tot 2)
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1001@10.0.9.237) State REPORTING
send 856 bytes to udp/[10.0.224.75]:59213 at 12:26:48.660354:
------------------------------------------------------------------------
SIP/2.0 408 Request Timeout
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPj875cf251a07f4718a0047d318bf41f03
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>;tag=6HUag70U043Sp
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25646 INVITE
User-Agent: FreeSWITCH
Accept: application/sdp
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-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Reason: Q.850;cause=102;text="RECOVERY_ON_TIMER_EXPIRE"
Content-Length: 0
Remote-Party-ID: "71576677" <sip:71576677@10.0.9.237>;party=calling;privacy=off;screen=no




2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:168 sofia/internal/1001@10.0.9.237 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1001@10.0.9.237) State REPORTING going to sleep
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/1001@10.0.9.237) State Change CS_REPORTING -> CS_DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_session.c:1744 Session 1 (sofia/internal/1001@10.0.9.237) Locked, Waiting on external entities
2024-07-02 12:26:48.656224 99.33% [NOTICE] switch_core_session.c:1762 Session 1 (sofia/internal/1001@10.0.9.237) Ended
2024-07-02 12:26:48.656224 99.33% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/1001@10.0.9.237 [CS_DESTROY]
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/1001@10.0.9.237) Running State Change CS_DESTROY (Cur 0 Tot 2)
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1001@10.0.9.237) State DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] mod_sofia.c:380 sofia/internal/1001@10.0.9.237 SOFIA DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:175 sofia/internal/1001@10.0.9.237 Standard DESTROY
2024-07-02 12:26:48.656224 99.33% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1001@10.0.9.237) State DESTROY going to sleep
recv 380 bytes from udp/[10.0.224.75]:59213 at 12:26:48.661643:
------------------------------------------------------------------------
ACK sip:971576677@10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPj875cf251a07f4718a0047d318bf41f03
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=181c13457a954e989694d84b487452f6
To: <sip:971576677@10.0.9.237>;tag=6HUag70U043Sp
Call-ID: d313dae897e04b13b4f1cbfc431f9f9d
CSeq: 25646 ACK
Route: <sip:10.0.9.237;lr>
Content-Length:  0




recv 550 bytes from udp/[10.0.9.50]:59516 at 12:30:42.492154:
------------------------------------------------------------------------
REGISTER sip:10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.9.50:59516;rport;branch=z9hG4bKPj523ea694f7444dbfb3ab9b8917593c64
Route: <sip:10.0.9.237;lr>
Max-Forwards: 70
From: "1002" <sip:1002@10.0.9.237>;tag=3ab622f4e76642f39fdc87d2e06ddf7d
To: "1002" <sip:1002@10.0.9.237>
Call-ID: 853fb241e0ad44b6b598cd1f566798f9
CSeq: 40450 REGISTER
User-Agent: MicroSIP/3.21.3
Contact: "1002" <sip:1002@10.0.9.50:59516;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length:  0




send 611 bytes to udp/[10.0.9.50]:59516 at 12:30:42.493617:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.0.9.50:59516;rport=59516;branch=z9hG4bKPj523ea694f7444dbfb3ab9b8917593c64
From: "1002" <sip:1002@10.0.9.237>;tag=3ab622f4e76642f39fdc87d2e06ddf7d
To: "1002" <sip:1002@10.0.9.237>;tag=9c7mNrK6QZ6HS
Call-ID: 853fb241e0ad44b6b598cd1f566798f9
CSeq: 40450 REGISTER
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
WWW-Authenticate: Digest realm="10.0.9.237", nonce="ea800c5d-313b-4ad2-8f1f-5ee1682a95a7", algorithm=MD5, qop="auth"
Content-Length: 0




recv 803 bytes from udp/[10.0.9.50]:59516 at 12:30:42.494280:
------------------------------------------------------------------------
REGISTER sip:10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.9.50:59516;rport;branch=z9hG4bKPj0c1026aba73d44ecac949577d2553b8c
Route: <sip:10.0.9.237;lr>
Max-Forwards: 70
From: "1002" <sip:1002@10.0.9.237>;tag=3ab622f4e76642f39fdc87d2e06ddf7d
To: "1002" <sip:1002@10.0.9.237>
Call-ID: 853fb241e0ad44b6b598cd1f566798f9
CSeq: 40451 REGISTER
User-Agent: MicroSIP/3.21.3
Contact: "1002" <sip:1002@10.0.9.50:59516;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="1002", realm="10.0.9.237", nonce="ea800c5d-313b-4ad2-8f1f-5ee1682a95a7", uri="sip:10.0.9.237", response="a272cfde3a310cbc614acd26596e74a8", algorithm=MD5, cnonce="079c738fdc9049a7a52669a0ebb46b17", qop=auth, nc=00000001
Content-Length:  0




send 572 bytes to udp/[10.0.9.50]:59516 at 12:30:42.499765:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.9.50:59516;rport=59516;branch=z9hG4bKPj0c1026aba73d44ecac949577d2553b8c
From: "1002" <sip:1002@10.0.9.237>;tag=3ab622f4e76642f39fdc87d2e06ddf7d
To: "1002" <sip:1002@10.0.9.237>;tag=ap0DQK49m8v4m
Call-ID: 853fb241e0ad44b6b598cd1f566798f9
CSeq: 40451 REGISTER
Contact: <sip:1002@10.0.9.50:59516;ob>;expires=300
Date: Tue, 02 Jul 2024 19:30:42 GMT
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
Content-Length: 0




recv 554 bytes from udp/[10.0.224.75]:59213 at 12:31:14.345874:
------------------------------------------------------------------------
REGISTER sip:10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPj31235adf925743988f133e9fa34cd824
Route: <sip:10.0.9.237;lr>
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=b4b8854d1da94b9d8ec601fc717c78a8
To: "1001" <sip:1001@10.0.9.237>
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37839 REGISTER
User-Agent: MicroSIP/3.21.3
Contact: "1001" <sip:1001@10.0.224.75:59213;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length:  0




send 613 bytes to udp/[10.0.224.75]:59213 at 12:31:14.347035:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPj31235adf925743988f133e9fa34cd824
From: "1001" <sip:1001@10.0.9.237>;tag=b4b8854d1da94b9d8ec601fc717c78a8
To: "1001" <sip:1001@10.0.9.237>;tag=BZS6reNDjHKQg
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37839 REGISTER
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
WWW-Authenticate: Digest realm="10.0.9.237", nonce="ef273f15-7ddf-4da2-ba43-b9885f3b2f6a", algorithm=MD5, qop="auth"
Content-Length: 0




recv 807 bytes from udp/[10.0.224.75]:59213 at 12:31:14.348048:
------------------------------------------------------------------------
REGISTER sip:10.0.9.237 SIP/2.0
Via: SIP/2.0/UDP 10.0.224.75:59213;rport;branch=z9hG4bKPjba8aa2e66ff04d51859521fc91dd0853
Route: <sip:10.0.9.237;lr>
Max-Forwards: 70
From: "1001" <sip:1001@10.0.9.237>;tag=b4b8854d1da94b9d8ec601fc717c78a8
To: "1001" <sip:1001@10.0.9.237>
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37840 REGISTER
User-Agent: MicroSIP/3.21.3
Contact: "1001" <sip:1001@10.0.224.75:59213;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="1001", realm="10.0.9.237", nonce="ef273f15-7ddf-4da2-ba43-b9885f3b2f6a", uri="sip:10.0.9.237", response="f9bc0aaa2eccd323f80d81d1e90bd86d", algorithm=MD5, cnonce="a48ede39796244eb9a0fbd76d9088f84", qop=auth, nc=00000001
Content-Length:  0




send 576 bytes to udp/[10.0.224.75]:59213 at 12:31:14.353384:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.224.75:59213;rport=59213;branch=z9hG4bKPjba8aa2e66ff04d51859521fc91dd0853
From: "1001" <sip:1001@10.0.9.237>;tag=b4b8854d1da94b9d8ec601fc717c78a8
To: "1001" <sip:1001@10.0.9.237>;tag=c8jZt95gFt99B
Call-ID: 5c1402ca287d416c9eca89c27b4ab1f3
CSeq: 37840 REGISTER
Contact: <sip:1001@10.0.224.75:59213;ob>;expires=300
Date: Tue, 02 Jul 2024 19:31:14 GMT
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
Content-Length: 0