freeswitchforum.com

Форум поддержки FreeSWITCH

FreeSWITCH is a registered trademark of Anthony Minessale. Official FreeSWITCH site.

Текущее время: 28 мар 2024 23:29

Часовой пояс: UTC + 4 часа




Начать новую тему Ответить на тему  [ Сообщений: 3 ] 
Автор Сообщение
СообщениеДобавлено: 25 сен 2020 14:48 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 9
Добрый день.
Помогите пожалуйста в настройке freeswith.
На сервере две сетевые карты. Одна сеть для регистрации локального абонента. Вторая сеть для прямого соединения с сип сервером с фиксированным адресом без регистрации.
Что работает:
Регистрация абонентов. Входящие вызовы с транка на внутренних абонентов. Входящие и исходящие вызовы между локальными абонентами.
Что не работает:
Исходящая связь на внешних абонентов. Входящее соединение обрывается через 10 секунд.

[+] лог исходящей связи через транк
recv 909 bytes from udp/[10.18.203.36]:5060 at 07:06:40.584653:
------------------------------------------------------------------------
INVITE sip:22202@10.18.203.10:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK688699303
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>
Call-ID: 0_2821122408@10.18.203.36
CSeq: 1 INVITE
Contact: <sip:22198@10.18.203.36:5060>
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
Max-Forwards: 70
User-Agent: Yealink SIP-T21P_E2 52.80.0.44
Allow-Events: talk,hold,conference,refer,check-sync
Supported: replaces
Content-Length: 306

v=0
o=- 20077 20077 IN IP4 10.18.203.36
s=SDP data
c=IN IP4 10.18.203.36
t=0 0
m=audio 11934 RTP/AVP 9 0 8 18 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.579653 [NOTICE] switch_channel.c:1114 New Channel sofia/internal/22198@10.18.203.10:5060 [0c6cd572-f94b-47fa-b724-3a3909c6de5b]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.579653 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_NEW (Cur 1 Tot 13)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.579653 [DEBUG] sofia.c:10164 sofia/internal/22198@10.18.203.10:5060 receiving invite from 10.18.203.36:5060 version: 1.8.7 64bit
2020-09-25 10:06:40.579653 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 10.18.203.36:0.
send 800 bytes to udp/[10.18.203.36]:5060 at 07:06:40.589653:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK688699303
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>;tag=3FBr8Bvct1Hre
Call-ID: 0_2821122408@10.18.203.36
CSeq: 1 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, 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.18.203.10", nonce="40a623a5-c118-4457-b19d-b3a42a924c23", algorithm=MD5, qop="auth"
Content-Length: 0

------------------------------------------------------------------------
2020-09-25 10:06:40.589653 [DEBUG] sofia.c:2413 detaching session 0c6cd572-f94b-47fa-b724-3a3909c6de5b
2020-09-25 10:06:40.589653 [DEBUG] sofia_reg.c:1794 Send challenge for [22202@10.18.203.10]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.589653 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/22198@10.18.203.10:5060) State NEW
recv 287 bytes from udp/[10.18.203.36]:5060 at 07:06:40.595653:
------------------------------------------------------------------------
ACK sip:22202@10.18.203.10:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK688699303
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>;tag=3FBr8Bvct1Hre
Call-ID: 0_2821122408@10.18.203.36
CSeq: 1 ACK
Content-Length: 0

------------------------------------------------------------------------
recv 1161 bytes from udp/[10.18.203.36]:5060 at 07:06:40.602654:
------------------------------------------------------------------------
INVITE sip:22202@10.18.203.10:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1259892526
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>
Call-ID: 0_2821122408@10.18.203.36
CSeq: 2 INVITE
Contact: <sip:22198@10.18.203.36:5060>
Proxy-Authorization: Digest username="22198", realm="10.18.203.10", nonce="40a623a5-c118-4457-b19d-b3a42a924c23", uri="sip:22202@10.18.203.10:5060", response="f409f6bde09cdeae20663f95842a6d94", algorithm=MD5, cnonce="0a4f113b", qop=auth, nc=00000001
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
Max-Forwards: 70
User-Agent: Yealink SIP-T21P_E2 52.80.0.44
Allow-Events: talk,hold,conference,refer,check-sync
Supported: replaces
Content-Length: 306

v=0
o=- 20077 20077 IN IP4 10.18.203.36
s=SDP data
c=IN IP4 10.18.203.36
t=0 0
m=audio 11934 RTP/AVP 9 0 8 18 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
2020-09-25 10:06:40.599654 [DEBUG] sofia.c:2522 Re-attaching to session 0c6cd572-f94b-47fa-b724-3a3909c6de5b
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] sofia.c:10164 sofia/internal/22198@10.18.203.10:5060 receiving invite from 10.18.203.36:5060 version: 1.8.7 64bit
2020-09-25 10:06:40.609654 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 10.18.203.36:0.
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'record_stereo' = 'false'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'toll_allow' = 'domestic,international,local'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'accountcode' = '22198'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'user_context' = 'office'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'effective_caller_id_name' = 'Zabava'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'effective_caller_id_number' = '22198'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'outbound_caller_id_number' = '22198'
2020-09-25 10:06:40.609654 [DEBUG] sofia_reg.c:3191 event_add_header -> 'callgroup' = 'techsupport'
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [received][100]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] sofia.c:7333 Remote SDP:
0c6cd572-f94b-47fa-b724-3a3909c6de5b v=0
0c6cd572-f94b-47fa-b724-3a3909c6de5b o=- 20077 20077 IN IP4 10.18.203.36
0c6cd572-f94b-47fa-b724-3a3909c6de5b s=SDP data
0c6cd572-f94b-47fa-b724-3a3909c6de5b c=IN IP4 10.18.203.36
0c6cd572-f94b-47fa-b724-3a3909c6de5b t=0 0
0c6cd572-f94b-47fa-b724-3a3909c6de5b m=audio 11934 RTP/AVP 9 0 8 18 101
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=rtpmap:9 G722/8000
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=rtpmap:0 PCMU/8000
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=rtpmap:8 PCMA/8000
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=rtpmap:18 G729/8000
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=fmtp:18 annexb=no
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=rtpmap:101 telephone-event/8000
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=fmtp:101 0-15
0c6cd572-f94b-47fa-b724-3a3909c6de5b a=ptime:20
0c6cd572-f94b-47fa-b724-3a3909c6de5b
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5427 Set telephone-event payload to 101@8000
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/22198@10.18.203.10:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_codec.c:111 sofia/internal/22198@10.18.203.10:5060 Original read codec set to G722:9
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5770 Set telephone-event payload to 101@8000
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_media.c:5828 sofia/internal/22198@10.18.203.10:5060 Set 2833 dtmf send payload to 101 recv payload to 101
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] sofia.c:7748 (sofia/internal/22198@10.18.203.10:5060) State Change CS_NEW -> CS_INIT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.609654 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_INIT (Cur 1 Tot 13)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/22198@10.18.203.10:5060) State INIT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] mod_sofia.c:93 sofia/internal/22198@10.18.203.10:5060 SOFIA INIT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:40 sofia/internal/22198@10.18.203.10:5060 Standard INIT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/22198@10.18.203.10:5060) State Change CS_INIT -> CS_ROUTING
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/22198@10.18.203.10:5060) State INIT going to sleep
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_ROUTING (Cur 1 Tot 13)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_channel.c:2293 (sofia/internal/22198@10.18.203.10:5060) Callstate Change DOWN -> RINGING
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/22198@10.18.203.10:5060) State ROUTING
send 298 bytes to udp/[10.18.203.36]:5060 at 07:06:40.620655:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1259892526
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>
Call-ID: 0_2821122408@10.18.203.36
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Content-Length: 0

------------------------------------------------------------------------
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] mod_sofia.c:154 sofia/internal/22198@10.18.203.10:5060 SOFIA ROUTING
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:236 sofia/internal/22198@10.18.203.10:5060 Standard ROUTING
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [INFO] mod_dialplan_xml.c:637 Processing ТЕСТ <22198>->22202 in context office
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->unloop] continue=false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [unloop] true() =~ /^true$/ break=on-false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->in-22198] continue=false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /^(1019)$/ break=on-false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->in-22198] continue=false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /22198/ break=on-false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->out-22198] continue=false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [out-22198] destination_number(22202) =~ /^(8\d{9,14})$/ break=on-false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->gsv-22198] continue=false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (PASS) [gsv-22198] destination_number(22202) =~ /^(2\d{4})$/ break=on-false
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Action set(effective_caller_id_number=22198)
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Action set(effective_caller_id_name=22198)
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Action export(nolocal:absolute_codec_string=PCMA,PCMU)
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Action bridge(sofia/gateway/TEST/22202)
0c6cd572-f94b-47fa-b724-3a3909c6de5b Dialplan: sofia/internal/22198@10.18.203.10:5060 Action hangup()
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/22198@10.18.203.10:5060) State Change CS_ROUTING -> CS_EXECUTE
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/22198@10.18.203.10:5060) State ROUTING going to sleep
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_EXECUTE (Cur 1 Tot 13)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.10:5060) State EXECUTE
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] mod_sofia.c:209 sofia/internal/22198@10.18.203.10:5060 SOFIA EXECUTE
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_core_state_machine.c:328 sofia/internal/22198@10.18.203.10:5060 Standard EXECUTE
0c6cd572-f94b-47fa-b724-3a3909c6de5b EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 set(effective_caller_id_number=22198)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.10:5060 [effective_caller_id_number]=[22198]
0c6cd572-f94b-47fa-b724-3a3909c6de5b EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 set(effective_caller_id_name=22198)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.10:5060 [effective_caller_id_name]=[22198]
0c6cd572-f94b-47fa-b724-3a3909c6de5b EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 export(nolocal:absolute_codec_string=PCMA,PCMU)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.619655 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) (REMOTE ONLY) [absolute_codec_string]=[PCMA,PCMU]
0c6cd572-f94b-47fa-b724-3a3909c6de5b EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 bridge(sofia/gateway/TEST/22202)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.629655 [DEBUG] switch_channel.c:1260 sofia/internal/22198@10.18.203.10:5060 EXPORTING[export_vars] [absolute_codec_string]=[PCMA,PCMU] to event
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.629655 [DEBUG] switch_ivr_originate.c:2209 Parsing global variables
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [NOTICE] switch_channel.c:1114 New Channel sofia/external/22202 [9e161ee3-0fec-4784-8f6b-ea20cdb6a56c]
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] mod_sofia.c:5031 (sofia/external/22202) State Change CS_NEW -> CS_INIT
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_INIT (Cur 2 Tot 14)
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] switch_core_state_machine.c:627 (sofia/external/22202) State INIT
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] mod_sofia.c:93 sofia/external/22202 SOFIA INIT
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] sofia_glue.c:1315 sofia/external/22202 sending invite version: 1.8.7 64bit
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c Local SDP:
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c v=0
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c o=FreeSWITCH 1600986082 1600986083 IN IP4 10.18.201.26
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c s=FreeSWITCH
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c c=IN IP4 10.18.201.26
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c t=0 0
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c m=audio 31518 RTP/AVP 8 0 101
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c a=rtpmap:8 PCMA/8000
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c a=rtpmap:0 PCMU/8000
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c a=rtpmap:101 telephone-event/8000
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c a=fmtp:101 0-16
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c a=ptime:20
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c a=sendrecv
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c
send 1015 bytes to udp/[10.18.201.15]:5060 at 07:06:40.637656:
------------------------------------------------------------------------
INVITE sip:22202@10.18.201.15 SIP/2.0
Via: SIP/2.0/UDP 10.18.201.26;rport;branch=z9hG4bKvZ6aK63jy0g5B
Max-Forwards: 69
From: "22198" <sip:22198@10.18.201.15>;tag=0FjB1e0aB37FF
To: <sip:22202@10.18.201.15>
Call-ID: 80862562-79a0-1239-7784-137e9a8b48d4
CSeq: 25955008 INVITE
Contact: <sip:gw+TEST@10.18.201.26:5060;transport=udp;gw=TEST>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 244
X-FS-Support: update_display,send_info
Remote-Party-ID: "22198" <sip:22198@10.18.201.15>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1600986082 1600986083 IN IP4 10.18.201.26
s=FreeSWITCH
c=IN IP4 10.18.201.26
t=0 0
m=audio 31518 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] switch_core_state_machine.c:40 sofia/external/22202 Standard INIT
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] switch_core_state_machine.c:48 (sofia/external/22202) State Change CS_INIT -> CS_ROUTING
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] switch_core_state_machine.c:627 (sofia/external/22202) State INIT going to sleep
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_ROUTING (Cur 2 Tot 14)
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.629655 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [calling][0]
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.639656 [DEBUG] switch_core_state_machine.c:643 (sofia/external/22202) State ROUTING
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.639656 [DEBUG] mod_sofia.c:154 sofia/external/22202 SOFIA ROUTING
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.639656 [DEBUG] switch_ivr_originate.c:67 (sofia/external/22202) State Change CS_ROUTING -> CS_CONSUME_MEDIA
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.639656 [DEBUG] switch_core_state_machine.c:643 (sofia/external/22202) State ROUTING going to sleep
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.639656 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 14)
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.639656 [DEBUG] switch_core_state_machine.c:662 (sofia/external/22202) State CONSUME_MEDIA
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.639656 [DEBUG] switch_core_state_machine.c:662 (sofia/external/22202) State CONSUME_MEDIA going to sleep
recv 291 bytes from udp/[10.18.201.15]:5060 at 07:06:40.642656:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Call-ID: 80862562-79a0-1239-7784-137e9a8b48d4
CSeq: 25955008 INVITE
From: "22198" <sip:22198@10.18.201.15>;tag=0FjB1e0aB37FF
To: <sip:22202@10.18.201.15>
Via: SIP/2.0/UDP 10.18.201.26;received=10.18.201.26;branch=z9hG4bKvZ6aK63jy0g5B;rport=5060
Content-Length: 0

------------------------------------------------------------------------
recv 441 bytes from udp/[10.18.201.15]:5060 at 07:06:40.651657:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Allow: INVITE,ACK,CANCEL,INFO,PRACK,UPDATE,OPTIONS,REGISTER,REFER,SUBSCRIBE,PUBLISH
Call-ID: 80862562-79a0-1239-7784-137e9a8b48d4
Contact: <sip:22202@10.18.201.15:5060>
CSeq: 25955008 INVITE
From: "22198" <sip:22198@10.18.201.15>;tag=0FjB1e0aB37FF
To: <sip:22202@10.18.201.15>;tag=mkx4o9mb12
Via: SIP/2.0/UDP 10.18.201.26;received=10.18.201.26;branch=z9hG4bKvZ6aK63jy0g5B;rport=5060
Content-Length: 0

------------------------------------------------------------------------
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.649657 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [proceeding][183]
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.649657 [NOTICE] sofia.c:7433 Ring-Ready sofia/external/22202!
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:40.649657 [DEBUG] switch_channel.c:3398 (sofia/external/22202) Callstate Change DOWN -> RINGING
send 806 bytes to udp/[10.18.203.36]:5060 at 07:06:40.659657:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1259892526
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>;tag=4r4ga7cgQa8aa
Call-ID: 0_2821122408@10.18.203.36
CSeq: 2 INVITE
Contact: <sip:22202@10.18.201.26:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, 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-Length: 0
Remote-Party-ID: "Outbound Call" <sip:22202@10.18.203.10>;party=calling;privacy=off;screen=no

------------------------------------------------------------------------
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.659657 [NOTICE] mod_sofia.c:2420 Ring-Ready sofia/internal/22198@10.18.203.10:5060!
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.659657 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [early][180]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:40.659657 [NOTICE] switch_ivr_originate.c:553 Ring Ready sofia/internal/22198@10.18.203.10:5060!
recv 372 bytes from udp/[10.18.201.15]:5060 at 07:06:45.648942:
------------------------------------------------------------------------
SIP/2.0 480 Temporarily Unavailable
Call-ID: 80862562-79a0-1239-7784-137e9a8b48d4
CSeq: 25955008 INVITE
From: "22198" <sip:22198@10.18.201.15>;tag=0FjB1e0aB37FF
To: <sip:22202@10.18.201.15>;tag=mkx4o9mb12
Via: SIP/2.0/UDP 10.18.201.26;received=10.18.201.26;branch=z9hG4bKvZ6aK63jy0g5B;rport=5060
Reason: Q.850;cause=20;text="Subscriber absent"
Content-Length: 0

------------------------------------------------------------------------
send 310 bytes to udp/[10.18.201.15]:5060 at 07:06:45.649943:
------------------------------------------------------------------------
ACK sip:22202@10.18.201.15 SIP/2.0
Via: SIP/2.0/UDP 10.18.201.26;rport;branch=z9hG4bKvZ6aK63jy0g5B
Max-Forwards: 69
From: "22198" <sip:22198@10.18.201.15>;tag=0FjB1e0aB37FF
To: <sip:22202@10.18.201.15>;tag=mkx4o9mb12
Call-ID: 80862562-79a0-1239-7784-137e9a8b48d4
CSeq: 25955008 ACK
Content-Length: 0

------------------------------------------------------------------------
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] sofia.c:6494 Remote Reason: 20
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [terminated][480]
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [NOTICE] sofia.c:8516 Hangup sofia/external/22202 [CS_CONSUME_MEDIA] [SUBSCRIBER_ABSENT]
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_HANGUP (Cur 2 Tot 14)
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:847 (sofia/external/22202) Callstate Change RINGING -> HANGUP
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:849 (sofia/external/22202) State HANGUP
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] mod_sofia.c:460 Channel sofia/external/22202 hanging up, cause: SUBSCRIBER_ABSENT
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:60 sofia/external/22202 Standard HANGUP, cause: SUBSCRIBER_ABSENT
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:849 (sofia/external/22202) State HANGUP going to sleep
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:619 (sofia/external/22202) State Change CS_HANGUP -> CS_REPORTING
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_REPORTING (Cur 2 Tot 14)
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:935 (sofia/external/22202) State REPORTING
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:174 sofia/external/22202 Standard REPORTING, cause: SUBSCRIBER_ABSENT
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:935 (sofia/external/22202) State REPORTING going to sleep
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_state_machine.c:610 (sofia/external/22202) State Change CS_REPORTING -> CS_DESTROY
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.649943 [DEBUG] switch_core_session.c:1726 Session 14 (sofia/external/22202) Locked, Waiting on external entities
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] switch_ivr_originate.c:3946 Originate Resulted in Error Cause: 20 [SUBSCRIBER_ABSENT]
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.659943 [NOTICE] switch_core_session.c:1744 Session 14 (sofia/external/22202) Ended
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.659943 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/22202 [CS_DESTROY]
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:738 (sofia/external/22202) Running State Change CS_DESTROY (Cur 1 Tot 14)
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:748 (sofia/external/22202) State DESTROY
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.659943 [DEBUG] mod_sofia.c:365 sofia/external/22202 SOFIA DESTROY
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:181 sofia/external/22202 Standard DESTROY
9e161ee3-0fec-4784-8f6b-ea20cdb6a56c 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:748 (sofia/external/22202) State DESTROY going to sleep
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [INFO] mod_dptools.c:3582 Originate Failed. Cause: SUBSCRIBER_ABSENT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [NOTICE] switch_channel.c:4901 Hangup sofia/internal/22198@10.18.203.10:5060 [CS_EXECUTE] [SUBSCRIBER_ABSENT]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] switch_core_session.c:2905 sofia/internal/22198@10.18.203.10:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.10:5060) State EXECUTE going to sleep
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_HANGUP (Cur 1 Tot 14)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/22198@10.18.203.10:5060) Callstate Change RINGING -> HANGUP
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/22198@10.18.203.10:5060) State HANGUP
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] mod_sofia.c:454 sofia/internal/22198@10.18.203.10:5060 Overriding SIP cause 480 with 480 from the other leg
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.659943 [DEBUG] mod_sofia.c:460 Channel sofia/internal/22198@10.18.203.10:5060 hanging up, cause: SUBSCRIBER_ABSENT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] mod_sofia.c:600 Responding to INVITE with: 480
send 810 bytes to udp/[10.18.203.36]:5060 at 07:06:45.670944:
------------------------------------------------------------------------
SIP/2.0 480 Temporarily Unavailable
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1259892526
Max-Forwards: 70
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>;tag=4r4ga7cgQa8aa
Call-ID: 0_2821122408@10.18.203.36
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, 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=20;text="SUBSCRIBER_ABSENT"
Content-Length: 0
Remote-Party-ID: "Outbound Call" <sip:22202@10.18.203.10>;party=calling;privacy=off;screen=no

------------------------------------------------------------------------
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:60 sofia/internal/22198@10.18.203.10:5060 Standard HANGUP, cause: SUBSCRIBER_ABSENT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/22198@10.18.203.10:5060) State HANGUP going to sleep
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/22198@10.18.203.10:5060) State Change CS_HANGUP -> CS_REPORTING
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_REPORTING (Cur 1 Tot 14)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/22198@10.18.203.10:5060) State REPORTING
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:174 sofia/internal/22198@10.18.203.10:5060 Standard REPORTING, cause: SUBSCRIBER_ABSENT
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/22198@10.18.203.10:5060) State REPORTING going to sleep
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/22198@10.18.203.10:5060) State Change CS_REPORTING -> CS_DESTROY
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_session.c:1726 Session 13 (sofia/internal/22198@10.18.203.10:5060) Locked, Waiting on external entities
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [NOTICE] switch_core_session.c:1744 Session 13 (sofia/internal/22198@10.18.203.10:5060) Ended
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/22198@10.18.203.10:5060 [CS_DESTROY]
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_DESTROY (Cur 0 Tot 14)
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/22198@10.18.203.10:5060) State DESTROY
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] mod_sofia.c:365 sofia/internal/22198@10.18.203.10:5060 SOFIA DESTROY
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:181 sofia/internal/22198@10.18.203.10:5060 Standard DESTROY
0c6cd572-f94b-47fa-b724-3a3909c6de5b 2020-09-25 10:06:45.669944 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/22198@10.18.203.10:5060) State DESTROY going to sleep
recv 288 bytes from udp/[10.18.203.36]:5060 at 07:06:45.677944:
------------------------------------------------------------------------
ACK sip:22202@10.18.203.10:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1259892526
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=4077714347
To: <sip:22202@10.18.203.10:5060>;tag=4r4ga7cgQa8aa
Call-ID: 0_2821122408@10.18.203.36
CSeq: 2 ACK
Content-Length: 0


Буду признателен за любую помощь.


Вернуться к началу
 Профиль  
 
СообщениеДобавлено: 25 сен 2020 16:01 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 9
В общем решил пока так.

<param name="inbound-bypass-media" value="true"/>

Исходящая связь на внешних абонентов. в internal.xml

Входящее соединение обрывается через 10 секунд. external.xml

теперь нет КПВ при исходящем на внешний номер.

[+] log
recv 909 bytes from udp/[10.18.203.36]:5060 at 11:49:42.409573:
------------------------------------------------------------------------
INVITE sip:22202@10.18.203.10:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1220939154
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>
Call-ID: 0_768827314@10.18.203.36
CSeq: 1 INVITE
Contact: <sip:22198@10.18.203.36:5060>
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
Max-Forwards: 70
User-Agent: Yealink SIP-T21P_E2 52.80.0.44
Allow-Events: talk,hold,conference,refer,check-sync
Supported: replaces
Content-Length: 306

v=0
o=- 20101 20101 IN IP4 10.18.203.36
s=SDP data
c=IN IP4 10.18.203.36
t=0 0
m=audio 11982 RTP/AVP 9 0 8 18 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.405572 [NOTICE] switch_channel.c:1114 New Channel sofia/internal/22198@10.18.203.10:5060 [93acb693-3c31-4dd8-a0a6-5e8b0df17127]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.405572 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_NEW (Cur 1 Tot 7)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.405572 [DEBUG] sofia.c:10164 sofia/internal/22198@10.18.203.10:5060 receiving invite from 10.18.203.36:5060 version: 1.8.7 64bit
2020-09-25 14:49:42.405572 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 10.18.203.36:0.
send 800 bytes to udp/[10.18.203.36]:5060 at 11:49:42.414573:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1220939154
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>;tag=8F2tyS45U442H
Call-ID: 0_768827314@10.18.203.36
CSeq: 1 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, 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.18.203.10", nonce="bb804fe1-a5c3-495e-8219-96643adc6da3", algorithm=MD5, qop="auth"
Content-Length: 0

------------------------------------------------------------------------
2020-09-25 14:49:42.405572 [DEBUG] sofia.c:2413 detaching session 93acb693-3c31-4dd8-a0a6-5e8b0df17127
2020-09-25 14:49:42.405572 [DEBUG] sofia_reg.c:1794 Send challenge for [22202@10.18.203.10]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.405572 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/22198@10.18.203.10:5060) State NEW
recv 287 bytes from udp/[10.18.203.36]:5060 at 11:49:42.419573:
------------------------------------------------------------------------
ACK sip:22202@10.18.203.10:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1220939154
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>;tag=8F2tyS45U442H
Call-ID: 0_768827314@10.18.203.36
CSeq: 1 ACK
Content-Length: 0

------------------------------------------------------------------------
recv 1160 bytes from udp/[10.18.203.36]:5060 at 11:49:42.427574:
------------------------------------------------------------------------
INVITE sip:22202@10.18.203.10:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1602945545
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>
Call-ID: 0_768827314@10.18.203.36
CSeq: 2 INVITE
Contact: <sip:22198@10.18.203.36:5060>
Proxy-Authorization: Digest username="22198", realm="10.18.203.10", nonce="bb804fe1-a5c3-495e-8219-96643adc6da3", uri="sip:22202@10.18.203.10:5060", response="396b0c2778d0e556ea9195b1532f6aeb", algorithm=MD5, cnonce="0a4f113b", qop=auth, nc=00000001
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
Max-Forwards: 70
User-Agent: Yealink SIP-T21P_E2 52.80.0.44
Allow-Events: talk,hold,conference,refer,check-sync
Supported: replaces
Content-Length: 306

v=0
o=- 20101 20101 IN IP4 10.18.203.36
s=SDP data
c=IN IP4 10.18.203.36
t=0 0
m=audio 11982 RTP/AVP 9 0 8 18 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
2020-09-25 14:49:42.425574 [DEBUG] sofia.c:2522 Re-attaching to session 93acb693-3c31-4dd8-a0a6-5e8b0df17127
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.425574 [DEBUG] sofia.c:10164 sofia/internal/22198@10.18.203.10:5060 receiving invite from 10.18.203.36:5060 version: 1.8.7 64bit
2020-09-25 14:49:42.425574 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 10.18.203.36:0.
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'record_stereo' = 'false'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'toll_allow' = 'domestic,international,local'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'accountcode' = '22198'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'user_context' = 'office'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'effective_caller_id_name' = 'Zabava'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'effective_caller_id_number' = '22198'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'outbound_caller_id_number' = '22198'
2020-09-25 14:49:42.435574 [DEBUG] sofia_reg.c:3191 event_add_header -> 'callgroup' = 'techsupport'
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [received][100]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] sofia.c:7333 Remote SDP:
93acb693-3c31-4dd8-a0a6-5e8b0df17127 v=0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 o=- 20101 20101 IN IP4 10.18.203.36
93acb693-3c31-4dd8-a0a6-5e8b0df17127 s=SDP data
93acb693-3c31-4dd8-a0a6-5e8b0df17127 c=IN IP4 10.18.203.36
93acb693-3c31-4dd8-a0a6-5e8b0df17127 t=0 0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 m=audio 11982 RTP/AVP 9 0 8 18 101
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:9 G722/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:0 PCMU/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:8 PCMA/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:18 G729/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=fmtp:18 annexb=no
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:101 telephone-event/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=fmtp:101 0-15
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=ptime:20
93acb693-3c31-4dd8-a0a6-5e8b0df17127
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] sofia.c:7713 (sofia/internal/22198@10.18.203.10:5060) State Change CS_NEW -> CS_INIT
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_INIT (Cur 1 Tot 7)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/22198@10.18.203.10:5060) State INIT
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] mod_sofia.c:93 sofia/internal/22198@10.18.203.10:5060 SOFIA INIT
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:40 sofia/internal/22198@10.18.203.10:5060 Standard INIT
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/22198@10.18.203.10:5060) State Change CS_INIT -> CS_ROUTING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/22198@10.18.203.10:5060) State INIT going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_ROUTING (Cur 1 Tot 7)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_channel.c:2293 (sofia/internal/22198@10.18.203.10:5060) Callstate Change DOWN -> RINGING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/22198@10.18.203.10:5060) State ROUTING
send 297 bytes to udp/[10.18.203.36]:5060 at 11:49:42.442575:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1602945545
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>
Call-ID: 0_768827314@10.18.203.36
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Content-Length: 0

------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] mod_sofia.c:154 sofia/internal/22198@10.18.203.10:5060 SOFIA ROUTING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:236 sofia/internal/22198@10.18.203.10:5060 Standard ROUTING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [INFO] mod_dialplan_xml.c:637 Processing ТЕСТ <22198>->22202 in context office
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->unloop] continue=false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [unloop] true() =~ /^true$/ break=on-false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->in-22198] continue=false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /^(1019)$/ break=on-false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->in-22198] continue=false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /22198/ break=on-false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->out-22198] continue=false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (FAIL) [out-22198] destination_number(22202) =~ /^(8\d{9,14})$/ break=on-false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 parsing [office->gsv-22198] continue=false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Regex (PASS) [gsv-22198] destination_number(22202) =~ /^(2\d{4})$/ break=on-false
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Action set(effective_caller_id_number=22198)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Action set(effective_caller_id_name=22198)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Action export(nolocal:absolute_codec_string=PCMA,PCMU)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Action bridge(sofia/gateway/TEST/22202)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Dialplan: sofia/internal/22198@10.18.203.10:5060 Action hangup()
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/22198@10.18.203.10:5060) State Change CS_ROUTING -> CS_EXECUTE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.435574 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/22198@10.18.203.10:5060) State ROUTING going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_EXECUTE (Cur 1 Tot 7)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.10:5060) State EXECUTE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] mod_sofia.c:209 sofia/internal/22198@10.18.203.10:5060 SOFIA EXECUTE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] switch_core_state_machine.c:328 sofia/internal/22198@10.18.203.10:5060 Standard EXECUTE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 set(effective_caller_id_number=22198)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.10:5060 [effective_caller_id_number]=[22198]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 set(effective_caller_id_name=22198)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.10:5060 [effective_caller_id_name]=[22198]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 export(nolocal:absolute_codec_string=PCMA,PCMU)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) (REMOTE ONLY) [absolute_codec_string]=[PCMA,PCMU]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 bridge(sofia/gateway/TEST/22202)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] switch_channel.c:1260 sofia/internal/22198@10.18.203.10:5060 EXPORTING[export_vars] [absolute_codec_string]=[PCMA,PCMU] to event
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.446575 [DEBUG] switch_ivr_originate.c:2209 Parsing global variables
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.446575 [NOTICE] switch_channel.c:1114 New Channel sofia/external/22202 [debd1689-a429-4a66-afdc-05af8619d06d]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.446575 [DEBUG] mod_sofia.c:5031 (sofia/external/22202) State Change CS_NEW -> CS_INIT
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.446575 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_INIT (Cur 2 Tot 8)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.446575 [DEBUG] switch_core_state_machine.c:627 (sofia/external/22202) State INIT
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.446575 [DEBUG] mod_sofia.c:93 sofia/external/22202 SOFIA INIT
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] sofia_glue.c:1315 sofia/external/22202 sending invite version: 1.8.7 64bit
debd1689-a429-4a66-afdc-05af8619d06d Local SDP:
debd1689-a429-4a66-afdc-05af8619d06d v=0
debd1689-a429-4a66-afdc-05af8619d06d o=- 20101 20101 IN IP4 10.18.203.36
debd1689-a429-4a66-afdc-05af8619d06d s=SDP data
debd1689-a429-4a66-afdc-05af8619d06d c=IN IP4 10.18.203.36
debd1689-a429-4a66-afdc-05af8619d06d t=0 0
debd1689-a429-4a66-afdc-05af8619d06d m=audio 11982 RTP/AVP 9 0 8 18 101
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:9 G722/8000
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:0 PCMU/8000
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:8 PCMA/8000
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:18 G729/8000
debd1689-a429-4a66-afdc-05af8619d06d a=fmtp:18 annexb=no
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:101 telephone-event/8000
debd1689-a429-4a66-afdc-05af8619d06d a=fmtp:101 0-15
debd1689-a429-4a66-afdc-05af8619d06d a=ptime:20
debd1689-a429-4a66-afdc-05af8619d06d
send 1064 bytes to udp/[10.18.201.15]:5060 at 11:49:42.455575:
------------------------------------------------------------------------
INVITE sip:22202@10.18.201.15 SIP/2.0
Via: SIP/2.0/UDP 10.18.201.26;rport;branch=z9hG4bK9gB7g0aD0a5pH
Max-Forwards: 69
From: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
To: <sip:22202@10.18.201.15>
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 25963499 INVITE
Contact: <sip:gw+TEST@10.18.201.26:5060;transport=udp;gw=TEST>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 294
X-FS-Support: update_display,send_info
Remote-Party-ID: "22198" <sip:22198@10.18.201.15>;party=calling;screen=yes;privacy=off

v=0
o=- 20101 20101 IN IP4 10.18.203.36
s=SDP data
c=IN IP4 10.18.203.36
t=0 0
m=audio 11982 RTP/AVP 9 0 8 18 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:40 sofia/external/22202 Standard INIT
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:48 (sofia/external/22202) State Change CS_INIT -> CS_ROUTING
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:627 (sofia/external/22202) State INIT going to sleep
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_ROUTING (Cur 2 Tot 8)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [calling][0]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:643 (sofia/external/22202) State ROUTING
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] mod_sofia.c:154 sofia/external/22202 SOFIA ROUTING
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_ivr_originate.c:67 (sofia/external/22202) State Change CS_ROUTING -> CS_CONSUME_MEDIA
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:643 (sofia/external/22202) State ROUTING going to sleep
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 8)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:662 (sofia/external/22202) State CONSUME_MEDIA
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.455575 [DEBUG] switch_core_state_machine.c:662 (sofia/external/22202) State CONSUME_MEDIA going to sleep
recv 290 bytes from udp/[10.18.201.15]:5060 at 11:49:42.461576:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 25963499 INVITE
From: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
To: <sip:22202@10.18.201.15>
Via: SIP/2.0/UDP 10.18.201.26;received=10.18.201.26;branch=z9hG4bK9gB7g0aD0a5pH;rport=5060
Content-Length: 0

------------------------------------------------------------------------
recv 440 bytes from udp/[10.18.201.15]:5060 at 11:49:42.469576:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Allow: INVITE,ACK,CANCEL,INFO,PRACK,UPDATE,OPTIONS,REGISTER,REFER,SUBSCRIBE,PUBLISH
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
Contact: <sip:22202@10.18.201.15:5060>
CSeq: 25963499 INVITE
From: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
To: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
Via: SIP/2.0/UDP 10.18.201.26;received=10.18.201.26;branch=z9hG4bK9gB7g0aD0a5pH;rport=5060
Content-Length: 0

------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.465576 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [proceeding][183]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.465576 [NOTICE] sofia.c:7433 Ring-Ready sofia/external/22202!
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.465576 [DEBUG] switch_channel.c:3398 (sofia/external/22202) Callstate Change DOWN -> RINGING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.475576 [NOTICE] mod_sofia.c:2420 Ring-Ready sofia/internal/22198@10.18.203.10:5060!
send 805 bytes to udp/[10.18.203.36]:5060 at 11:49:42.478577:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1602945545
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
Call-ID: 0_768827314@10.18.203.36
CSeq: 2 INVITE
Contact: <sip:22202@10.18.203.10:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, 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-Length: 0
Remote-Party-ID: "Outbound Call" <sip:22202@10.18.203.10>;party=calling;privacy=off;screen=no

------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.475576 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [early][180]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.475576 [NOTICE] switch_ivr_originate.c:553 Ring Ready sofia/internal/22198@10.18.203.10:5060!
recv 736 bytes from udp/[10.18.201.15]:5060 at 11:49:42.553581:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Allow: INVITE,ACK,CANCEL,INFO,PRACK,UPDATE,OPTIONS,REGISTER,REFER,SUBSCRIBE,PUBLISH
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
Contact: "22202" <sip:22202@10.18.201.15:5060>
CSeq: 25963499 INVITE
From: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
To: "22202" <sip:22202@10.18.201.15>;tag=bv1kup4wlq
Via: SIP/2.0/UDP 10.18.201.26;received=10.18.201.26;branch=z9hG4bK9gB7g0aD0a5pH;rport=5060
P-Asserted-Identity: "22202" <sip:22202@10.18.201.15>
Content-Length: 152
Content-Type: application/sdp
Content-Disposition: session;handling=required

v=0
o=- 8484881 7875356 IN IP4 10.18.201.25
s=-
c=IN IP4 10.18.201.25
t=0 0
m=audio 23836 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendonly
------------------------------------------------------------------------
2020-09-25 14:49:42.555581 [INFO] sofia.c:1356 sofia/external/22202 Update Callee ID to "22202" <22202>
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.555581 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [proceeding][180]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.555581 [DEBUG] sofia.c:7333 Remote SDP:
debd1689-a429-4a66-afdc-05af8619d06d v=0
debd1689-a429-4a66-afdc-05af8619d06d o=- 8484881 7875356 IN IP4 10.18.201.25
debd1689-a429-4a66-afdc-05af8619d06d s=-
debd1689-a429-4a66-afdc-05af8619d06d c=IN IP4 10.18.201.25
debd1689-a429-4a66-afdc-05af8619d06d t=0 0
debd1689-a429-4a66-afdc-05af8619d06d m=audio 23836 RTP/AVP 8
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:8 PCMA/8000
debd1689-a429-4a66-afdc-05af8619d06d a=sendonly
debd1689-a429-4a66-afdc-05af8619d06d a=ptime:20
debd1689-a429-4a66-afdc-05af8619d06d
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.555581 [NOTICE] sofia.c:7336 Pre-Answer sofia/external/22202!
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.555581 [DEBUG] switch_channel.c:3526 (sofia/external/22202) Callstate Change RINGING -> EARLY
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.555581 [DEBUG] switch_ivr_originate.c:3803 Originate Resulted in Success: [sofia/external/22202]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.555581 [INFO] switch_ivr.c:819 Sending early media
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.555581 [NOTICE] mod_sofia.c:2518 Pre-Answer sofia/internal/22198@10.18.203.10:5060!
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.555581 [DEBUG] switch_channel.c:3526 (sofia/internal/22198@10.18.203.10:5060) Callstate Change RINGING -> EARLY
send 1004 bytes to udp/[10.18.203.36]:5060 at 11:49:42.564582:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1602945545
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
Call-ID: 0_768827314@10.18.203.36
CSeq: 2 INVITE
Contact: <sip:22202@10.18.203.10:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, 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: 152
Remote-Party-ID: "Outbound Call" <sip:22202@10.18.203.10>;party=calling;privacy=off;screen=no

v=0
o=- 8484881 7875356 IN IP4 10.18.201.25
s=-
c=IN IP4 10.18.201.25
t=0 0
m=audio 23836 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendonly
a=ptime:20
------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] switch_ivr_bridge.c:1591 (sofia/internal/22198@10.18.203.10:5060) State Change CS_EXECUTE -> CS_HIBERNATE
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.565582 [DEBUG] switch_ivr_bridge.c:1592 (sofia/external/22202) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_HIBERNATE (Cur 2 Tot 8)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:665 (sofia/external/22202) State HIBERNATE
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.565582 [DEBUG] mod_sofia.c:191 sofia/external/22202 SOFIA HIBERNATE
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:420 sofia/external/22202 Standard HIBERNATE
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:665 (sofia/external/22202) State HIBERNATE going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [early][183]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.10:5060) State EXECUTE going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_HIBERNATE (Cur 2 Tot 8)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:665 (sofia/internal/22198@10.18.203.10:5060) State HIBERNATE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] mod_sofia.c:191 sofia/internal/22198@10.18.203.10:5060 SOFIA HIBERNATE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:420 sofia/internal/22198@10.18.203.10:5060 Standard HIBERNATE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:42.565582 [DEBUG] switch_core_state_machine.c:665 (sofia/internal/22198@10.18.203.10:5060) State HIBERNATE going to sleep
recv 848 bytes from udp/[10.18.201.15]:5060 at 11:49:45.172731:
------------------------------------------------------------------------
SIP/2.0 200 OK
Accept: application/sdp;q=0.3
Accept: application/ISUP;q=0.2
Accept: multipart/mixed;q=0.1
Allow: INVITE,ACK,CANCEL,BYE,INFO,PRACK,OPTIONS,REGISTER,REFER,SUBSCRIBE,MESSAGE,PUBLISH,UPDATE
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
Contact: "22202" <sip:22202@10.18.201.15:5060>
CSeq: 25963499 INVITE
From: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
To: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
Supported: 100rel
Via: SIP/2.0/UDP 10.18.201.26;received=10.18.201.26;branch=z9hG4bK9gB7g0aD0a5pH;rport=5060
P-Asserted-Identity: "22202" <sip:22202@10.18.201.15>
Content-Length: 152
Content-Type: application/sdp
Content-Disposition: session;handling=required

v=0
o=- 8484881 7875356 IN IP4 10.18.201.25
s=-
c=IN IP4 10.18.201.25
t=0 0
m=audio 23836 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendonly
------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.165730 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [completing][200]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.175731 [DEBUG] sofia.c:7330 Duplicate SDP
debd1689-a429-4a66-afdc-05af8619d06d v=0
debd1689-a429-4a66-afdc-05af8619d06d o=- 8484881 7875356 IN IP4 10.18.201.25
debd1689-a429-4a66-afdc-05af8619d06d s=-
debd1689-a429-4a66-afdc-05af8619d06d c=IN IP4 10.18.201.25
debd1689-a429-4a66-afdc-05af8619d06d t=0 0
debd1689-a429-4a66-afdc-05af8619d06d m=audio 23836 RTP/AVP 8
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:8 PCMA/8000
debd1689-a429-4a66-afdc-05af8619d06d a=sendonly
debd1689-a429-4a66-afdc-05af8619d06d a=ptime:20
debd1689-a429-4a66-afdc-05af8619d06d
send 378 bytes to udp/[10.18.201.15]:5060 at 11:49:45.175731:
------------------------------------------------------------------------
ACK sip:22202@10.18.201.15:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.201.26;rport;branch=z9hG4bKat4ZjUUgXKU9c
Max-Forwards: 70
From: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
To: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 25963499 ACK
Contact: <sip:gw+TEST@10.18.201.26:5060;transport=udp;gw=TEST>
Content-Length: 0

------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.175731 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [ready][200]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.175731 [NOTICE] sofia.c:8402 Channel [sofia/external/22202] has been answered
recv 896 bytes from udp/[10.18.201.15]:5060 at 11:49:45.176731:
------------------------------------------------------------------------
INVITE sip:gw+TEST@10.18.201.26:5060;transport=UDP;gw=TEST SIP/2.0
Accept: application/sdp;q=0.3
Accept: application/ISUP;q=0.2
Accept: multipart/mixed;q=0.1
Allow: INVITE,ACK,CANCEL,BYE,INFO,PRACK,UPDATE,OPTIONS,REGISTER,REFER,SUBSCRIBE,MESSAGE,PUBLISH
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
Contact: "22202" <sip:22202@10.18.201.15:5060>
CSeq: 736 INVITE
Expires: 3600
From: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
To: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
Organization: Iskratel
User-Agent: SI3000
Via: SIP/2.0/UDP 10.18.201.15:5060;branch=z9hG4bK-shlog-hbv23
Max-Forwards: 70
Subject: Call from SI3000
Content-Length: 152
Content-Type: application/sdp
Content-Disposition: session;handling=required

v=0
o=- 8484881 7875357 IN IP4 10.18.203.19
s=-
c=IN IP4 10.18.203.19
t=0 0
m=audio 12718 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.175731 [DEBUG] switch_channel.c:3825 (sofia/external/22202) Callstate Change EARLY -> ACTIVE
send 317 bytes to udp/[10.18.201.15]:5060 at 11:49:45.178731:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.18.201.15:5060;branch=z9hG4bK-shlog-hbv23
From: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
To: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 736 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Content-Length: 0

------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.175731 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [received][100]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.175731 [DEBUG] sofia.c:7333 Remote SDP:
debd1689-a429-4a66-afdc-05af8619d06d v=0
debd1689-a429-4a66-afdc-05af8619d06d o=- 8484881 7875357 IN IP4 10.18.203.19
debd1689-a429-4a66-afdc-05af8619d06d s=-
debd1689-a429-4a66-afdc-05af8619d06d c=IN IP4 10.18.203.19
debd1689-a429-4a66-afdc-05af8619d06d t=0 0
debd1689-a429-4a66-afdc-05af8619d06d m=audio 12718 RTP/AVP 8
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:8 PCMA/8000
debd1689-a429-4a66-afdc-05af8619d06d a=ptime:20
debd1689-a429-4a66-afdc-05af8619d06d
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.175731 [DEBUG] sofia.c:8071 Passing SDP to other leg.
debd1689-a429-4a66-afdc-05af8619d06d v=0
debd1689-a429-4a66-afdc-05af8619d06d o=- 8484881 7875357 IN IP4 10.18.203.19
debd1689-a429-4a66-afdc-05af8619d06d s=-
debd1689-a429-4a66-afdc-05af8619d06d c=IN IP4 10.18.203.19
debd1689-a429-4a66-afdc-05af8619d06d t=0 0
debd1689-a429-4a66-afdc-05af8619d06d m=audio 12718 RTP/AVP 8
debd1689-a429-4a66-afdc-05af8619d06d a=rtpmap:8 PCMA/8000
debd1689-a429-4a66-afdc-05af8619d06d a=ptime:20
debd1689-a429-4a66-afdc-05af8619d06d
send 940 bytes to udp/[10.18.203.36]:5060 at 11:49:45.181731:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK1602945545
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
Call-ID: 0_768827314@10.18.203.36
CSeq: 2 INVITE
Contact: <sip:22202@10.18.203.10:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, 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-Length: 140
Remote-Party-ID: "22202" <sip:22202@10.18.203.10>;party=calling;privacy=off;screen=no

v=0
o=- 8484881 7875357 IN IP4 10.18.203.19
s=-
c=IN IP4 10.18.203.19
t=0 0
m=audio 12718 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.175731 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [completed][200]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.175731 [NOTICE] switch_ivr.c:814 Channel [sofia/internal/22198@10.18.203.10:5060] has been answered
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.186732 [DEBUG] switch_channel.c:3825 (sofia/internal/22198@10.18.203.10:5060) Callstate Change EARLY -> ACTIVE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.186732 [DEBUG] mod_sofia.c:1611 sofia/internal/22198@10.18.203.10:5060 Sending media re-direct:
93acb693-3c31-4dd8-a0a6-5e8b0df17127 v=0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 o=- 8484881 7875357 IN IP4 10.18.203.19
93acb693-3c31-4dd8-a0a6-5e8b0df17127 s=-
93acb693-3c31-4dd8-a0a6-5e8b0df17127 c=IN IP4 10.18.203.19
93acb693-3c31-4dd8-a0a6-5e8b0df17127 t=0 0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 m=audio 12718 RTP/AVP 8
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:8 PCMA/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=ptime:20
93acb693-3c31-4dd8-a0a6-5e8b0df17127
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.186732 [DEBUG] sofia_glue.c:1315 sofia/internal/22198@10.18.203.10:5060 sending invite version: 1.8.7 64bit
93acb693-3c31-4dd8-a0a6-5e8b0df17127 Local SDP:
93acb693-3c31-4dd8-a0a6-5e8b0df17127 v=0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 o=- 8484881 7875357 IN IP4 10.18.203.19
93acb693-3c31-4dd8-a0a6-5e8b0df17127 s=-
93acb693-3c31-4dd8-a0a6-5e8b0df17127 c=IN IP4 10.18.203.19
93acb693-3c31-4dd8-a0a6-5e8b0df17127 t=0 0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 m=audio 12718 RTP/AVP 8
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:8 PCMA/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=ptime:20
93acb693-3c31-4dd8-a0a6-5e8b0df17127
send 779 bytes to udp/[10.18.203.36]:5060 at 11:49:45.187732:
------------------------------------------------------------------------
INVITE sip:22198@10.18.203.36:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.10;rport;branch=z9hG4bK4KFX26vXX4cDp
Max-Forwards: 70
From: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
To: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
Call-ID: 0_768827314@10.18.203.36
CSeq: 25963500 INVITE
Contact: <sip:22202@10.18.203.10:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Type: application/sdp
Content-Length: 140
X-FS-Support: update_display,send_info

v=0
o=- 8484881 7875357 IN IP4 10.18.203.19
s=-
c=IN IP4 10.18.203.19
t=0 0
m=audio 12718 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.186732 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [calling][0]
recv 329 bytes from udp/[10.18.203.36]:5060 at 11:49:45.210733:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.18.203.10;rport=5060;branch=z9hG4bK4KFX26vXX4cDp
From: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
To: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
Call-ID: 0_768827314@10.18.203.36
CSeq: 25963500 INVITE
User-Agent: Yealink SIP-T21P_E2 52.80.0.44
Content-Length: 0

------------------------------------------------------------------------
recv 654 bytes from udp/[10.18.203.36]:5060 at 11:49:45.234734:
------------------------------------------------------------------------
ACK sip:22202@10.18.203.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.18.203.36:5060;branch=z9hG4bK2544107471
From: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
To: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
Call-ID: 0_768827314@10.18.203.36
CSeq: 2 ACK
Contact: <sip:22198@10.18.203.36:5060>
Proxy-Authorization: Digest username="22198", realm="10.18.203.10", nonce="bb804fe1-a5c3-495e-8219-96643adc6da3", uri="sip:22202@10.18.203.10:5060", response="396b0c2778d0e556ea9195b1532f6aeb", algorithm=MD5, cnonce="0a4f113b", qop=auth, nc=00000001
Max-Forwards: 70
User-Agent: Yealink SIP-T21P_E2 52.80.0.44
Content-Length: 0

------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.235734 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [ready][200]
recv 672 bytes from udp/[10.18.203.36]:5060 at 11:49:45.236734:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.18.203.10;rport=5060;branch=z9hG4bK4KFX26vXX4cDp
From: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
To: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
Call-ID: 0_768827314@10.18.203.36
CSeq: 25963500 INVITE
Contact: <sip:22198@10.18.203.36:5060>
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
User-Agent: Yealink SIP-T21P_E2 52.80.0.44
Content-Length: 155

v=0
o=- 20101 20102 IN IP4 10.18.203.36
s=SDP data
c=IN IP4 10.18.203.36
t=0 0
m=audio 11982 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.235734 [DEBUG] sofia.c:6893 Passing 200 OK to other leg
93acb693-3c31-4dd8-a0a6-5e8b0df17127 v=0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 o=- 20101 20102 IN IP4 10.18.203.36
93acb693-3c31-4dd8-a0a6-5e8b0df17127 s=SDP data
93acb693-3c31-4dd8-a0a6-5e8b0df17127 c=IN IP4 10.18.203.36
93acb693-3c31-4dd8-a0a6-5e8b0df17127 t=0 0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 m=audio 11982 RTP/AVP 8
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:8 PCMA/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=ptime:20
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=sendrecv
93acb693-3c31-4dd8-a0a6-5e8b0df17127
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.235734 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [completing][200]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.235734 [DEBUG] sofia.c:7330 Duplicate SDP
93acb693-3c31-4dd8-a0a6-5e8b0df17127 v=0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 o=- 20101 20102 IN IP4 10.18.203.36
93acb693-3c31-4dd8-a0a6-5e8b0df17127 s=SDP data
93acb693-3c31-4dd8-a0a6-5e8b0df17127 c=IN IP4 10.18.203.36
93acb693-3c31-4dd8-a0a6-5e8b0df17127 t=0 0
93acb693-3c31-4dd8-a0a6-5e8b0df17127 m=audio 11982 RTP/AVP 8
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=rtpmap:8 PCMA/8000
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=ptime:20
93acb693-3c31-4dd8-a0a6-5e8b0df17127 a=sendrecv
93acb693-3c31-4dd8-a0a6-5e8b0df17127
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.235734 [DEBUG] mod_sofia.c:2272 Responding with 200 [OK]
send 316 bytes to udp/[10.18.203.36]:5060 at 11:49:45.240735:
------------------------------------------------------------------------
ACK sip:22198@10.18.203.36:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.10;rport;branch=z9hG4bK5v8N41D1tD3ZH
Max-Forwards: 70
From: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
To: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
Call-ID: 0_768827314@10.18.203.36
CSeq: 25963500 ACK
Content-Length: 0

------------------------------------------------------------------------
send 732 bytes to udp/[10.18.201.15]:5060 at 11:49:45.241735:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.18.201.15:5060;branch=z9hG4bK-shlog-hbv23
From: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
To: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 736 INVITE93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:45.235734 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.10:5060 entering state [ready][200]

Contact: <sip:gw+TEST@10.18.201.26:5060;transport=udp;gw=TEST>
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 143

v=0
o=- 20101 20102 IN IP4 10.18.203.36
s=SDP data
c=IN IP4 10.18.203.36
t=0 0
m=audio 11982 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.235734 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [completed][200]
recv 331 bytes from udp/[10.18.201.15]:5060 at 11:49:45.242735:
------------------------------------------------------------------------
ACK sip:gw+TEST@10.18.201.26:5060;transport=UDP;gw=TEST SIP/2.0
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 736 ACK
From: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
To: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
Via: SIP/2.0/UDP 10.18.201.15:5060;branch=z9hG4bK-pv24q-cb4ta
Max-Forwards: 70
Content-Length: 0

------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:45.235734 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [ready][200]
recv 383 bytes from udp/[10.18.201.15]:5060 at 11:49:47.710876:
------------------------------------------------------------------------
BYE sip:gw+TEST@10.18.201.26:5060;transport=UDP;gw=TEST SIP/2.0
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 737 BYE
From: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
To: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
Via: SIP/2.0/UDP 10.18.201.15:5060;branch=z9hG4bK-vk9i9-p0ll3
Reason: Q.850;cause=16;text="Normal call clearing"
Max-Forwards: 70
Content-Length: 0

------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.705876 [NOTICE] sofia.c:1079 Hangup sofia/external/22202 [CS_HIBERNATE] [NORMAL_CLEARING]
send 434 bytes to udp/[10.18.201.15]:5060 at 11:49:47.713876:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.18.201.15:5060;branch=z9hG4bK-vk9i9-p0ll3
From: <sip:22202@10.18.201.15>;tag=bv1kup4wlq
To: "22198" <sip:2198@10.18.201.15>;tag=apB99v6SKjQcB
Call-ID: 0a7a12ee-79c8-1239-9b89-6101e9963928
CSeq: 737 BYE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Content-Length: 0

------------------------------------------------------------------------
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.705876 [DEBUG] switch_ivr_bridge.c:1338 sofia/external/22202 skip receive message [UNBRIDGE] (channel is hungup already)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.705876 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_HANGUP (Cur 2 Tot 8)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.705876 [DEBUG] switch_core_state_machine.c:847 (sofia/external/22202) Callstate Change ACTIVE -> HANGUP
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.705876 [DEBUG] switch_core_state_machine.c:849 (sofia/external/22202) State HANGUP
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.705876 [DEBUG] mod_sofia.c:460 Channel sofia/external/22202 hanging up, cause: NORMAL_CLEARING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.705876 [DEBUG] switch_ivr_bridge.c:1474 (sofia/internal/22198@10.18.203.10:5060) State Change CS_HIBERNATE -> CS_EXECUTE
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:60 sofia/external/22202 Standard HANGUP, cause: NORMAL_CLEARING
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:849 (sofia/external/22202) State HANGUP going to sleep
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:619 (sofia/external/22202) State Change CS_HANGUP -> CS_REPORTING
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_REPORTING (Cur 2 Tot 8)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:935 (sofia/external/22202) State REPORTING
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:174 sofia/external/22202 Standard REPORTING, cause: NORMAL_CLEARING
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:935 (sofia/external/22202) State REPORTING going to sleep
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:610 (sofia/external/22202) State Change CS_REPORTING -> CS_DESTROY
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_session.c:1726 Session 8 (sofia/external/22202) Locked, Waiting on external entities
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [NOTICE] switch_core_session.c:1744 Session 8 (sofia/external/22202) Ended
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/22202 [CS_DESTROY]
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:738 (sofia/external/22202) Running State Change CS_DESTROY (Cur 1 Tot 8)
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:748 (sofia/external/22202) State DESTROY
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] mod_sofia.c:365 sofia/external/22202 SOFIA DESTROY
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:181 sofia/external/22202 Standard DESTROY
debd1689-a429-4a66-afdc-05af8619d06d 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:748 (sofia/external/22202) State DESTROY going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_EXECUTE (Cur 1 Tot 8)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.10:5060) State EXECUTE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] mod_sofia.c:209 sofia/internal/22198@10.18.203.10:5060 SOFIA EXECUTE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:328 sofia/internal/22198@10.18.203.10:5060 Standard EXECUTE
93acb693-3c31-4dd8-a0a6-5e8b0df17127 EXECUTE [depth=0] sofia/internal/22198@10.18.203.10:5060 hangup()
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [NOTICE] mod_dptools.c:1351 Hangup sofia/internal/22198@10.18.203.10:5060 [CS_EXECUTE] [NORMAL_CLEARING]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_session.c:2905 sofia/internal/22198@10.18.203.10:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.10:5060) State EXECUTE going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_HANGUP (Cur 1 Tot 8)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/22198@10.18.203.10:5060) Callstate Change ACTIVE -> HANGUP
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/22198@10.18.203.10:5060) State HANGUP
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] mod_sofia.c:454 sofia/internal/22198@10.18.203.10:5060 Overriding SIP cause 480 with 200 from the other leg
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] mod_sofia.c:460 Channel sofia/internal/22198@10.18.203.10:5060 hanging up, cause: NORMAL_CLEARING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] mod_sofia.c:513 Sending BYE to sofia/internal/22198@10.18.203.10:5060
send 558 bytes to udp/[10.18.203.36]:5060 at 11:49:47.723877:
------------------------------------------------------------------------
BYE sip:22198@10.18.203.36:5060 SIP/2.0
Via: SIP/2.0/UDP 10.18.203.10;rport;branch=z9hG4bK651e6vy4QpSjD
Max-Forwards: 70
From: <sip:22202@10.18.203.10:5060>;tag=9rUK0mN9rDUND
To: "ТЕСТ" <sip:22198@10.18.203.10:5060>;tag=2793544001
Call-ID: 0_768827314@10.18.203.36
CSeq: 25963501 BYE
User-Agent: FreeSWITCH-mod_sofia/1.8.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: Q.850;cause=16;text="Normal call clearing"
Content-Length: 0

------------------------------------------------------------------------
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:60 sofia/internal/22198@10.18.203.10:5060 Standard HANGUP, cause: NORMAL_CLEARING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/22198@10.18.203.10:5060) State HANGUP going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.715876 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/22198@10.18.203.10:5060) State Change CS_HANGUP -> CS_REPORTING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_REPORTING (Cur 1 Tot 8)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/22198@10.18.203.10:5060) State REPORTING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:174 sofia/internal/22198@10.18.203.10:5060 Standard REPORTING, cause: NORMAL_CLEARING
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/22198@10.18.203.10:5060) State REPORTING going to sleep
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/22198@10.18.203.10:5060) State Change CS_REPORTING -> CS_DESTROY
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_session.c:1726 Session 7 (sofia/internal/22198@10.18.203.10:5060) Locked, Waiting on external entities
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [NOTICE] switch_core_session.c:1744 Session 7 (sofia/internal/22198@10.18.203.10:5060) Ended
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/22198@10.18.203.10:5060 [CS_DESTROY]
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/22198@10.18.203.10:5060) Running State Change CS_DESTROY (Cur 0 Tot 8)
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/22198@10.18.203.10:5060) State DESTROY
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] mod_sofia.c:365 sofia/internal/22198@10.18.203.10:5060 SOFIA DESTROY
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:181 sofia/internal/22198@10.18.203.10:5060 Standard DESTROY
93acb693-3c31-4dd8-a0a6-5e8b0df17127 2020-09-25 14:49:47.726877 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/22198@10.18.203.10:5060) State DESTROY going to sleep


Вернуться к началу
 Профиль  
 
СообщениеДобавлено: 25 сен 2020 17:54 
FreeSWITCH_GuRu

Зарегистрирован: 22 авг 2012 09:52
Сообщения: 1710
похоже на кривую маршрутизацию
с серыми сетями всегда куча подобных проблем
рисуйте схему взаимодействия сетей так будет проще

_________________
ЛС: @rostel
Сообщество: @ru_freeswitch


Вернуться к началу
 Профиль  
 
Показать сообщения за:  Поле сортировки  
Начать новую тему Ответить на тему  [ Сообщений: 3 ] 

Часовой пояс: UTC + 4 часа


Кто сейчас на конференции

Сейчас этот форум просматривают: нет зарегистрированных пользователей и гости: 39


Вы не можете начинать темы
Вы не можете отвечать на сообщения
Вы не можете редактировать свои сообщения
Вы не можете удалять свои сообщения
Вы не можете добавлять вложения

Найти:
Перейти:  
cron
Powered by phpBB® Forum Software © phpBB Group
Русская поддержка phpBB