freeswitchforum.com https://freeswitchforum.com/ |
|
Проблема с исходящей и входящей связью. https://freeswitchforum.com/viewtopic.php?f=6&t=1084 |
Страница 1 из 1 |
Автор: | Evgeniy [ 25 сен 2020 14:48 ] |
Заголовок сообщения: | Проблема с исходящей и входящей связью. |
Добрый день. Помогите пожалуйста в настройке 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 Буду признателен за любую помощь. |
Автор: | Evgeniy [ 25 сен 2020 16:01 ] |
Заголовок сообщения: | Re: Проблема с исходящей и входящей связью. |
В общем решил пока так. <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 |
Автор: | Vlad1983 [ 25 сен 2020 17:54 ] |
Заголовок сообщения: | Re: Проблема с исходящей и входящей связью. |
похоже на кривую маршрутизацию с серыми сетями всегда куча подобных проблем рисуйте схему взаимодействия сетей так будет проще |
Страница 1 из 1 | Часовой пояс: UTC + 4 часа |
Powered by phpBB® Forum Software © phpBB Group http://www.phpbb.com/ |