freeswitchforum.com

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

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

Текущее время: 21 сен 2020 12:18

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




Начать новую тему Ответить на тему  [ Сообщений: 9 ] 
Автор Сообщение
 Заголовок сообщения: Ошибка при записи разговоров
СообщениеДобавлено: 28 авг 2020 15:47 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 7
Всем здравствуйте.
При включении записи в диал плане внутреннего номера вызов с него на внешний gateway не проходит.
В логах: [INFO] mod_dptools.c:3582 Originate Failed. Cause: BEARERCAPABILITY_NOTAUTH.
С внешнего gateway вызов проходит и запись происходит.

Где что я мог упустить?
Заранее спасибо.


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 28 авг 2020 18:37 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 7
Добавлю полный лог

[+] лог
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.019229 [NOTICE] switch_channel.c:1114 New Channel sofia/internal/22198@10.18.203.9:5060 [016776a5-290e-48cd-b5d8-b21f90d11230]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.019229 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_NEW (Cur 1 Tot 13)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.019229 [DEBUG] sofia.c:10164 sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.8.7 64bit
2020-08-28 16:00:16.019229 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 10.18.203.36:0.
2020-08-28 16:00:16.029230 [DEBUG] sofia.c:2413 detaching session 016776a5-290e-48cd-b5d8-b21f90d11230
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.029230 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/22198@10.18.203.9:5060) State NEW
2020-08-28 16:00:16.039231 [DEBUG] sofia.c:2522 Re-attaching to session 016776a5-290e-48cd-b5d8-b21f90d11230
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.049231 [DEBUG] sofia.c:10164 sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.8.7 64bit
2020-08-28 16:00:16.049231 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 10.18.203.36:0.
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.9:5060 entering state [received][100]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] sofia.c:7333 Remote SDP:
016776a5-290e-48cd-b5d8-b21f90d11230 v=0
016776a5-290e-48cd-b5d8-b21f90d11230 o=- 20137 20137 IN IP4 10.18.203.36
016776a5-290e-48cd-b5d8-b21f90d11230 s=SDP data
016776a5-290e-48cd-b5d8-b21f90d11230 c=IN IP4 10.18.203.36
016776a5-290e-48cd-b5d8-b21f90d11230 t=0 0
016776a5-290e-48cd-b5d8-b21f90d11230 m=audio 12054 RTP/AVP 9 0 8 18 101
016776a5-290e-48cd-b5d8-b21f90d11230 a=rtpmap:9 G722/8000
016776a5-290e-48cd-b5d8-b21f90d11230 a=rtpmap:0 PCMU/8000
016776a5-290e-48cd-b5d8-b21f90d11230 a=rtpmap:8 PCMA/8000
016776a5-290e-48cd-b5d8-b21f90d11230 a=rtpmap:18 G729/8000
016776a5-290e-48cd-b5d8-b21f90d11230 a=fmtp:18 annexb=no
016776a5-290e-48cd-b5d8-b21f90d11230 a=rtpmap:101 telephone-event/8000
016776a5-290e-48cd-b5d8-b21f90d11230 a=fmtp:101 0-15
016776a5-290e-48cd-b5d8-b21f90d11230 a=ptime:20
016776a5-290e-48cd-b5d8-b21f90d11230
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] sofia.c:7727 (sofia/internal/22198@10.18.203.9:5060) State Change CS_NEW -> CS_INIT
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_INIT (Cur 1 Tot 13)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/22198@10.18.203.9:5060) State INIT
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] mod_sofia.c:93 sofia/internal/22198@10.18.203.9:5060 SOFIA INIT
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:40 sofia/internal/22198@10.18.203.9:5060 Standard INIT
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/22198@10.18.203.9:5060) State Change CS_INIT -> CS_ROUTING
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/22198@10.18.203.9:5060) State INIT going to sleep
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_ROUTING (Cur 1 Tot 13)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_channel.c:2293 (sofia/internal/22198@10.18.203.9:5060) Callstate Change DOWN -> RINGING
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/22198@10.18.203.9:5060) State ROUTING
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] mod_sofia.c:154 sofia/internal/22198@10.18.203.9:5060 SOFIA ROUTING
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:236 sofia/internal/22198@10.18.203.9:5060 Standard ROUTING
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [INFO] mod_dialplan_xml.c:637 Processing ТЕСТ <22198>->22202 in context office1
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->unloop] continue=false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [unloop] true() =~ /^true$/ break=on-false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->in-22198] continue=false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /^(22198)$/ break=on-false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->out-22198] continue=false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [out-22198] destination_number(22202) =~ /^(8\d{9,14})$/ break=on-false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->gsv-22198] continue=false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (PASS) [gsv-22198] destination_number(22202) =~ /^(2\d{4})$/ break=on-false
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_TITLE=Recording ${destination_number} ${caller_id_number} ${strftime(%Y-%m-%d %H:%M)})
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COPYRIGHT=(c) 2012)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_SOFTWARE=FreeSwitch)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_ARTIST=FreeSwitch)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COMMENT=Voice recordings)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_DATE=${strftime(%Y-%m-%d %H:%M)})
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_STEREO=true)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(media_bug_answer_req=true)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action record_session(C:/Program Files/FreeSWITCH/recordings/${strftime(%Y%m%d_%H%M%S)}_${destination_number}_${caller_id_number}.wav)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(ringback=${us-ring})
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action bridge(sofia/gateway/TR22198/22202)
016776a5-290e-48cd-b5d8-b21f90d11230 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action hangup()
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/22198@10.18.203.9:5060) State Change CS_ROUTING -> CS_EXECUTE
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.059232 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/22198@10.18.203.9:5060) State ROUTING going to sleep
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_EXECUTE (Cur 1 Tot 13)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_sofia.c:209 sofia/internal/22198@10.18.203.9:5060 SOFIA EXECUTE
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] switch_core_state_machine.c:328 sofia/internal/22198@10.18.203.9:5060 Standard EXECUTE
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_TITLE=Recording 22202 22198 2020-08-28 16:00)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_TITLE]=[Recording 22202 22198 2020-08-28 16:00]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_COPYRIGHT=(c) 2012)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_COPYRIGHT]=[(c) 2012]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_SOFTWARE=FreeSwitch)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_SOFTWARE]=[FreeSwitch]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_ARTIST=FreeSwitch)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_ARTIST]=[FreeSwitch]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_COMMENT=Voice recordings)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_COMMENT]=[Voice recordings]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_DATE=2020-08-28 16:00)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_DATE]=[2020-08-28 16:00]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_STEREO=true)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_STEREO]=[true]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(media_bug_answer_req=true)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [media_bug_answer_req]=[true]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [DEBUG] switch_core_session.c:2722 Application record_session Requires media! pre_answering channel sofia/internal/22198@10.18.203.9:5060
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.070232 [INFO] switch_core_session.c:2724 Sending early media
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5427 Set telephone-event payload to 101@8000
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/22198@10.18.203.9:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_codec.c:111 sofia/internal/22198@10.18.203.9:5060 Original read codec set to G722:9
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5770 Set telephone-event payload to 101@8000
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:5828 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101 recv payload to 101
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:8568 AUDIO RTP [sofia/internal/22198@10.18.203.9:5060] 10.18.203.9 port 32450 -> 10.18.203.36 port 12054 codec: 9 ms: 20
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_rtp.c:4349 Starting timer [soft] 160 bytes per 20ms
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:8875 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:8882 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf receive payload to 101
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:8905 sofia/internal/22198@10.18.203.9:5060 Set rtp dtmf delay to 40
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/22198@10.18.203.9:5060!
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_channel.c:3526 (sofia/internal/22198@10.18.203.9:5060) Callstate Change RINGING -> EARLY
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media.c:8551 Audio params are unchanged for sofia/internal/22198@10.18.203.9:5060.
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] mod_sofia.c:2515 Ring SDP:
016776a5-290e-48cd-b5d8-b21f90d11230 v=0
016776a5-290e-48cd-b5d8-b21f90d11230 o=FreeSWITCH 1598583566 1598583567 IN IP4 10.18.203.9
016776a5-290e-48cd-b5d8-b21f90d11230 s=FreeSWITCH
016776a5-290e-48cd-b5d8-b21f90d11230 c=IN IP4 10.18.203.9
016776a5-290e-48cd-b5d8-b21f90d11230 t=0 0
016776a5-290e-48cd-b5d8-b21f90d11230 m=audio 32450 RTP/AVP 9 101
016776a5-290e-48cd-b5d8-b21f90d11230 a=rtpmap:9 G722/8000
016776a5-290e-48cd-b5d8-b21f90d11230 a=rtpmap:101 telephone-event/8000
016776a5-290e-48cd-b5d8-b21f90d11230 a=fmtp:101 0-16
016776a5-290e-48cd-b5d8-b21f90d11230 a=ptime:20
016776a5-290e-48cd-b5d8-b21f90d11230 a=sendrecv
016776a5-290e-48cd-b5d8-b21f90d11230
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] sofia.c:7323 Channel sofia/internal/22198@10.18.203.9:5060 entering state [early][183]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 record_session(C:/Program Files/FreeSWITCH/recordings/20200828_160016_22202_22198.wav)
2020-08-28 16:00:16.079233 [INFO] mod_native_file.c:101 Opening File [C:/Program-in.G722] 16000hz
2020-08-28 16:00:16.079233 [INFO] mod_native_file.c:101 Opening File [C:/Program-out.G722] 16000hz
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.079233 [DEBUG] switch_core_media_bug.c:962 Attaching BUG to sofia/internal/22198@10.18.203.9:5060
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(ringback=%(2000,4000,440,480))
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.089233 [DEBUG] mod_dptools.c:1615 SET sofia/internal/22198@10.18.203.9:5060 [ringback]=[%(2000,4000,440,480)]
016776a5-290e-48cd-b5d8-b21f90d11230 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 bridge(sofia/gateway/TR22198/22202)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.089233 [DEBUG] switch_ivr_originate.c:2209 Parsing global variables
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.089233 [NOTICE] switch_channel.c:1114 New Channel sofia/external/22202 [54db275f-7020-4a50-acf5-2d3287915d83]
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.089233 [DEBUG] mod_sofia.c:5031 (sofia/external/22202) State Change CS_NEW -> CS_INIT
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_INIT (Cur 2 Tot 14)
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:627 (sofia/external/22202) State INIT
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] mod_sofia.c:93 sofia/external/22202 SOFIA INIT
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] sofia_glue.c:1315 sofia/external/22202 sending invite version: 1.8.7 64bit
54db275f-7020-4a50-acf5-2d3287915d83 Local SDP:
54db275f-7020-4a50-acf5-2d3287915d83 v=0
54db275f-7020-4a50-acf5-2d3287915d83 o=FreeSWITCH 1598594104 1598594105 IN IP4 10.18.203.9
54db275f-7020-4a50-acf5-2d3287915d83 s=FreeSWITCH
54db275f-7020-4a50-acf5-2d3287915d83 c=IN IP4 10.18.203.9
54db275f-7020-4a50-acf5-2d3287915d83 t=0 0
54db275f-7020-4a50-acf5-2d3287915d83 m=audio 21912 RTP/AVP 9 101
54db275f-7020-4a50-acf5-2d3287915d83 a=rtpmap:9 G722/8000
54db275f-7020-4a50-acf5-2d3287915d83 a=rtpmap:101 telephone-event/8000
54db275f-7020-4a50-acf5-2d3287915d83 a=fmtp:101 0-16
54db275f-7020-4a50-acf5-2d3287915d83 a=ptime:20
54db275f-7020-4a50-acf5-2d3287915d83 a=sendrecv
54db275f-7020-4a50-acf5-2d3287915d83
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:40 sofia/external/22202 Standard INIT
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:48 (sofia/external/22202) State Change CS_INIT -> CS_ROUTING
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:627 (sofia/external/22202) State INIT going to sleep
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_ROUTING (Cur 2 Tot 14)
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [calling][0]
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:643 (sofia/external/22202) State ROUTING
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] mod_sofia.c:154 sofia/external/22202 SOFIA ROUTING
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_ivr_originate.c:67 (sofia/external/22202) State Change CS_ROUTING -> CS_CONSUME_MEDIA
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:643 (sofia/external/22202) State ROUTING going to sleep
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 14)
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:662 (sofia/external/22202) State CONSUME_MEDIA
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] switch_core_state_machine.c:662 (sofia/external/22202) State CONSUME_MEDIA going to sleep
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.159237 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [calling][0]
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.159237 [DEBUG] sofia.c:6494 Remote Reason: 57
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] sofia.c:7323 Channel sofia/external/22202 entering state [terminated][403]
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [NOTICE] sofia.c:8516 Hangup sofia/external/22202 [CS_CONSUME_MEDIA] [BEARERCAPABILITY_NOTAUTH]
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_HANGUP (Cur 2 Tot 14)
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:847 (sofia/external/22202) Callstate Change DOWN -> HANGUP
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:849 (sofia/external/22202) State HANGUP
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] mod_sofia.c:460 Channel sofia/external/22202 hanging up, cause: BEARERCAPABILITY_NOTAUTH
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:60 sofia/external/22202 Standard HANGUP, cause: BEARERCAPABILITY_NOTAUTH
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:849 (sofia/external/22202) State HANGUP going to sleep
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:619 (sofia/external/22202) State Change CS_HANGUP -> CS_REPORTING
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:584 (sofia/external/22202) Running State Change CS_REPORTING (Cur 2 Tot 14)
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:935 (sofia/external/22202) State REPORTING
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:174 sofia/external/22202 Standard REPORTING, cause: BEARERCAPABILITY_NOTAUTH
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:935 (sofia/external/22202) State REPORTING going to sleep
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_state_machine.c:610 (sofia/external/22202) State Change CS_REPORTING -> CS_DESTROY
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.169238 [DEBUG] switch_core_session.c:1726 Session 14 (sofia/external/22202) Locked, Waiting on external entities
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_ivr_originate.c:3946 Originate Resulted in Error Cause: 57 [BEARERCAPABILITY_NOTAUTH]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [INFO] mod_dptools.c:3582 Originate Failed. Cause: BEARERCAPABILITY_NOTAUTH
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [NOTICE] switch_channel.c:4901 Hangup sofia/internal/22198@10.18.203.9:5060 [CS_EXECUTE] [BEARERCAPABILITY_NOTAUTH]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_core_session.c:2905 sofia/internal/22198@10.18.203.9:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE going to sleep
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_HANGUP (Cur 2 Tot 14)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_ivr_async.c:1441 Stop recording file C:/Program
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_core_media_bug.c:1276 Removing BUG from sofia/internal/22198@10.18.203.9:5060
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/22198@10.18.203.9:5060) Callstate Change EARLY -> HANGUP
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/22198@10.18.203.9:5060) State HANGUP
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] mod_sofia.c:454 sofia/internal/22198@10.18.203.9:5060 Overriding SIP cause 403 with 403 from the other leg
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.179239 [DEBUG] mod_sofia.c:460 Channel sofia/internal/22198@10.18.203.9:5060 hanging up, cause: BEARERCAPABILITY_NOTAUTH
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] mod_sofia.c:600 Responding to INVITE with: 403
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:60 sofia/internal/22198@10.18.203.9:5060 Standard HANGUP, cause: BEARERCAPABILITY_NOTAUTH
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/22198@10.18.203.9:5060) State HANGUP going to sleep
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/22198@10.18.203.9:5060) State Change CS_HANGUP -> CS_REPORTING
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_REPORTING (Cur 2 Tot 14)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/22198@10.18.203.9:5060) State REPORTING
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:174 sofia/internal/22198@10.18.203.9:5060 Standard REPORTING, cause: BEARERCAPABILITY_NOTAUTH
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/22198@10.18.203.9:5060) State REPORTING going to sleep
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/22198@10.18.203.9:5060) State Change CS_REPORTING -> CS_DESTROY
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_session.c:1726 Session 13 (sofia/internal/22198@10.18.203.9:5060) Locked, Waiting on external entities
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [NOTICE] switch_core_session.c:1744 Session 13 (sofia/internal/22198@10.18.203.9:5060) Ended
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/22198@10.18.203.9:5060 [CS_DESTROY]
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_DESTROY (Cur 1 Tot 14)
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/22198@10.18.203.9:5060) State DESTROY
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] mod_sofia.c:365 sofia/internal/22198@10.18.203.9:5060 SOFIA DESTROY
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.189239 [NOTICE] switch_core_session.c:1744 Session 14 (sofia/external/22202) Ended
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.189239 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/22202 [CS_DESTROY]
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:738 (sofia/external/22202) Running State Change CS_DESTROY (Cur 0 Tot 14)
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:748 (sofia/external/22202) State DESTROY
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.189239 [DEBUG] mod_sofia.c:365 sofia/external/22202 SOFIA DESTROY
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:181 sofia/external/22202 Standard DESTROY
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:748 (sofia/external/22202) State DESTROY going to sleep
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:181 sofia/internal/22198@10.18.203.9:5060 Standard DESTROY
016776a5-290e-48cd-b5d8-b21f90d11230 2020-08-28 16:00:16.189239 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/22198@10.18.203.9:5060) State DESTROY going to sleep


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 28 авг 2020 19:10 
FreeSWITCH_GuRu

Зарегистрирован: 22 авг 2012 09:52
Сообщения: 1667
лог с закомменченым record_session был бы не лишним

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


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 29 авг 2020 15:24 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 7
Vlad1983 писал(а):
лог с закомменченым record_session был бы не лишним

Что вы имеете ввиду?
Сейчас выходные. Собрал дома для теста.
Та же ситуация.
На внутренние номера запись тоже происходит.
Сильно не ругаться. Только учусь.


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 31 авг 2020 14:48 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 7
Лог с закоменченым record_session
[+] есть соединение
15:36:37.744689 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/22198@10.18.203.9:5060 [a56b3ec6-1a0d-469e-83da-73e46420fede]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.744689 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_NEW (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.744689 [DEBUG] sofia.c:10280 sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.10.5 64bit
2020-08-31 15:36:37.744689 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 10.18.203.36:0.
2020-08-31 15:36:37.754689 [DEBUG] sofia.c:2434 detaching session a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.754689 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/22198@10.18.203.9:5060) State NEW
2020-08-31 15:36:37.764690 [DEBUG] sofia.c:2544 Re-attaching to session a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.774691 [DEBUG] sofia.c:10280 sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.10.5 64bit
2020-08-31 15:36:37.774691 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 10.18.203.36:0.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [received][100]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] sofia.c:7336 Remote SDP:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=- 20151 20151 IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede s=SDP data
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 12082 RTP/AVP 9 0 8 18 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:0 PCMU/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:8 PCMA/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:18 G729/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:18 annexb=no
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-15
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] sofia.c:7739 (sofia/internal/22198@10.18.203.9:5060) State Change CS_NEW -> CS_INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_INIT (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/22198@10.18.203.9:5060) State INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_sofia.c:93 sofia/internal/22198@10.18.203.9:5060 SOFIA INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:40 sofia/internal/22198@10.18.203.9:5060 Standard INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/22198@10.18.203.9:5060) State Change CS_INIT -> CS_ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/22198@10.18.203.9:5060) State INIT going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_ROUTING (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_channel.c:2332 (sofia/internal/22198@10.18.203.9:5060) Callstate Change DOWN -> RINGING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/22198@10.18.203.9:5060) State ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_sofia.c:154 sofia/internal/22198@10.18.203.9:5060 SOFIA ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:236 sofia/internal/22198@10.18.203.9:5060 Standard ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [INFO] mod_dialplan_xml.c:637 Processing ТЕСТ <22198>->22202 in context office1
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->unloop] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [unloop] true() =~ /^true$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->in-22198] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /^(22198)$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->out-22198] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [out-22198] destination_number(22202) =~ /^(8\d{9,14})$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->gsv-22198] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (PASS) [gsv-22198] destination_number(22202) =~ /^(2\d{4})$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_TITLE=Recording ${destination_number} ${caller_id_number} ${strftime(%Y-%m-%d %H:%M)})
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COPYRIGHT=(c) 2012)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_SOFTWARE=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_ARTIST=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COMMENT=Voice recordings)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_DATE=${strftime(%Y-%m-%d %H:%M)})
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_STEREO=true)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(media_bug_answer_req=true)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(ringback=${us-ring})
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action bridge(sofia/gateway/TR22198/22202)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan: sofia/internal/22198@10.18.203.9:5060 Action hangup()
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/22198@10.18.203.9:5060) State Change CS_ROUTING -> CS_EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/22198@10.18.203.9:5060) State ROUTING going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_EXECUTE (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_sofia.c:209 sofia/internal/22198@10.18.203.9:5060 SOFIA EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:329 sofia/internal/22198@10.18.203.9:5060 Standard EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_TITLE=Recording 22202 22198 2020-08-31 15:36)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_TITLE]=[Recording 22202 22198 2020-08-31 15:36]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_COPYRIGHT=(c) 2012)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_COPYRIGHT]=[(c) 2012]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_SOFTWARE=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_SOFTWARE]=[FreeSwitch]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_ARTIST=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_ARTIST]=[FreeSwitch]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_COMMENT=Voice recordings)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_COMMENT]=[Voice recordings]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_DATE=2020-08-31 15:36)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_DATE]=[2020-08-31 15:36]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_STEREO=true)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_STEREO]=[true]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(media_bug_answer_req=true)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [media_bug_answer_req]=[true]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(ringback=%(2000,4000,440,480))
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [ringback]=[%(2000,4000,440,480)]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 bridge(sofia/gateway/TR22198/22202)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.794692 [NOTICE] switch_channel.c:1118 New Channel sofia/external/22202 [d88ada98-aef6-4f37-a2c1-ca2beda10e5b]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.794692 [DEBUG] mod_sofia.c:5089 (sofia/external/22202) State Change CS_NEW -> CS_INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_INIT (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] switch_core_state_machine.c:628 (sofia/external/22202) State INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] mod_sofia.c:93 sofia/external/22202 SOFIA INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] sofia_glue.c:1618 sofia/external/22202 sending invite version: 1.10.5 64bit
d88ada98-aef6-4f37-a2c1-ca2beda10e5b Local SDP:
d88ada98-aef6-4f37-a2c1-ca2beda10e5b v=0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b o=FreeSWITCH 1598843725 1598843726 IN IP4 10.18.203.9
d88ada98-aef6-4f37-a2c1-ca2beda10e5b s=FreeSWITCH
d88ada98-aef6-4f37-a2c1-ca2beda10e5b c=IN IP4 10.18.203.9
d88ada98-aef6-4f37-a2c1-ca2beda10e5b t=0 0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b m=audio 30072 RTP/AVP 9 0 8 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:9 G722/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:0 PCMU/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:8 PCMA/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:101 telephone-event/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=fmtp:101 0-16
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=ptime:20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=sendrecv
d88ada98-aef6-4f37-a2c1-ca2beda10e5b
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:40 sofia/external/22202 Standard INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:48 (sofia/external/22202) State Change CS_INIT -> CS_ROUTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:628 (sofia/external/22202) State INIT going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_ROUTING (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [calling][0]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:644 (sofia/external/22202) State ROUTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] mod_sofia.c:154 sofia/external/22202 SOFIA ROUTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_ivr_originate.c:67 (sofia/external/22202) State Change CS_ROUTING -> CS_CONSUME_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:644 (sofia/external/22202) State ROUTING going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:663 (sofia/external/22202) State CONSUME_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:663 (sofia/external/22202) State CONSUME_MEDIA going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [calling][0]
2020-08-31 15:36:38.024705 [INFO] sofia.c:1369 sofia/external/22202 Update Callee ID to "22202" <22202>
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [proceeding][180]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] sofia.c:7336 Remote SDP:
d88ada98-aef6-4f37-a2c1-ca2beda10e5b v=0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b o=- 8157652 6646641 IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b s=-
d88ada98-aef6-4f37-a2c1-ca2beda10e5b c=IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b t=0 0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b m=audio 23116 RTP/AVP 8
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:8 PCMA/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=sendonly
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=ptime:20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [NOTICE] sofia.c:7339 Pre-Answer sofia/external/22202!
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_channel.c:3565 (sofia/external/22202) Callstate Change DOWN -> EARLY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/22202 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_codec.c:111 sofia/external/22202 Original read codec set to PCMA:8
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5882 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5911 sofia/external/22202 Set 2833 dtmf send payload to 101 recv payload to 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/22202] 10.18.203.9 port 30072 -> 10.18.201.23 port 23116 codec: 8 ms: 20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:8977 sofia/external/22202 Set 2833 dtmf send payload to 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:8984 sofia/external/22202 Set 2833 dtmf receive payload to 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:9007 sofia/external/22202 Set rtp dtmf delay to 40
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [INFO] switch_ivr_originate.c:3801 Sending early media
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/22198@10.18.203.9:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_codec.c:111 sofia/internal/22198@10.18.203.9:5060 Original read codec set to G722:9
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5911 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101 recv payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/internal/22198@10.18.203.9:5060] 10.18.203.9 port 17408 -> 10.18.203.36 port 12082 codec: 9 ms: 20
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8977 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8984 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf receive payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:9007 sofia/internal/22198@10.18.203.9:5060 Set rtp dtmf delay to 40
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/22198@10.18.203.9:5060!
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_channel.c:3565 (sofia/internal/22198@10.18.203.9:5060) Callstate Change RINGING -> EARLY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/22198@10.18.203.9:5060.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] mod_sofia.c:2593 Ring SDP:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=FreeSWITCH 1598856390 1598856391 IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede s=FreeSWITCH
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 17408 RTP/AVP 9 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-16
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede a=recvonly
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [early][183]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/22202]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/22202) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] switch_core_state_machine.c:654 (sofia/external/22202) State EXCHANGE_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.084708 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.084708 [NOTICE] switch_core_media.c:15845 Activating write resampler
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.204715 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.204715 [NOTICE] switch_core_media.c:15845 Activating write resampler
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [completing][200]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] sofia.c:7333 Duplicate SDP
d88ada98-aef6-4f37-a2c1-ca2beda10e5b v=0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b o=- 8157652 6646641 IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b s=-
d88ada98-aef6-4f37-a2c1-ca2beda10e5b c=IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b t=0 0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b m=audio 23116 RTP/AVP 8
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:8 PCMA/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=sendonly
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=ptime:20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [ready][200]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [NOTICE] sofia.c:8445 Channel [sofia/external/22202] has been answered
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] switch_channel.c:3865 (sofia/external/22202) Callstate Change EARLY -> ACTIVE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/22198@10.18.203.9:5060.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/22198@10.18.203.9:5060:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=FreeSWITCH 1598856390 1598856392 IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede s=FreeSWITCH
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 17408 RTP/AVP 9 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-16
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede a=recvonly
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [completed][200]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [NOTICE] switch_ivr_bridge.c:731 Channel [sofia/internal/22198@10.18.203.9:5060] has been answered
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] switch_channel.c:3865 (sofia/internal/22198@10.18.203.9:5060) Callstate Change EARLY -> ACTIVE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.604852 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.604852 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [ready][200]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.624854 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [calling][0]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [ready][200]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] sofia.c:7336 Remote SDP:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=- 20151 20153 IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede s=SDP data
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 12082 RTP/AVP 9 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-15
a56b3ec6-1a0d-469e-83da-73e46420fede a=sendonly
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5911 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101 recv payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] sofia.c:8345 Processing updated SDP
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/22198@10.18.203.9:5060.
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.884868 [INFO] switch_rtp.c:7719 Auto Changing audio port from 10.18.201.23:23116 to 10.18.203.19:12202
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.944615 [NOTICE] sofia.c:1089 Hangup sofia/internal/22198@10.18.203.9:5060 [CS_EXECUTE] [NORMAL_CLEARING]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.944615 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/22198@10.18.203.9:5060]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/22202]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/external/22202 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:654 (sofia/external/22202) State EXCHANGE_MEDIA going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_HANGUP (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:848 (sofia/external/22202) Callstate Change ACTIVE -> HANGUP
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:850 (sofia/external/22202) State HANGUP
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:447 sofia/external/22202 Overriding SIP cause 480 with 200 from the other leg
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:453 Channel sofia/external/22202 hanging up, cause: NORMAL_CLEARING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/external/22202
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:60 sofia/external/22202 Standard HANGUP, cause: NORMAL_CLEARING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:850 (sofia/external/22202) State HANGUP going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:620 (sofia/external/22202) State Change CS_HANGUP -> CS_REPORTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_REPORTING (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:936 (sofia/external/22202) State REPORTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:174 sofia/external/22202 Standard REPORTING, cause: NORMAL_CLEARING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:936 (sofia/external/22202) State REPORTING going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:611 (sofia/external/22202) State Change CS_REPORTING -> CS_DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_session.c:1726 Session 12 (sofia/external/22202) Locked, Waiting on external entities
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_ivr_bridge.c:1895 sofia/internal/22198@10.18.203.9:5060 skip receive message [UNBRIDGE] (channel is hungup already)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_session.c:2905 sofia/internal/22198@10.18.203.9:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_HANGUP (Cur 2 Tot 12)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/22198@10.18.203.9:5060) Callstate Change ACTIVE -> HANGUP
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/22198@10.18.203.9:5060) State HANGUP
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:453 Channel sofia/internal/22198@10.18.203.9:5060 hanging up, cause: NORMAL_CLEARING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:60 sofia/internal/22198@10.18.203.9:5060 Standard HANGUP, cause: NORMAL_CLEARING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/22198@10.18.203.9:5060) State HANGUP going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/22198@10.18.203.9:5060) State Change CS_HANGUP -> CS_REPORTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_REPORTING (Cur 2 Tot 12)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/22198@10.18.203.9:5060) State REPORTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:174 sofia/internal/22198@10.18.203.9:5060 Standard REPORTING, cause: NORMAL_CLEARING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/22198@10.18.203.9:5060) State REPORTING going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/22198@10.18.203.9:5060) State Change CS_REPORTING -> CS_DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_session.c:1726 Session 11 (sofia/internal/22198@10.18.203.9:5060) Locked, Waiting on external entities
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1744 Session 11 (sofia/internal/22198@10.18.203.9:5060) Ended
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/22198@10.18.203.9:5060 [CS_DESTROY]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_DESTROY (Cur 1 Tot 12)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/22198@10.18.203.9:5060) State DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] mod_sofia.c:364 sofia/internal/22198@10.18.203.9:5060 SOFIA DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:181 sofia/internal/22198@10.18.203.9:5060 Standard DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/22198@10.18.203.9:5060) State DESTROY going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1744 Session 12 (sofia/external/22202) Ended
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/22202 [CS_DESTROY]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:739 (sofia/external/22202) Running State Change CS_DESTROY (Cur 0 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/external/22202) State DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] mod_sofia.c:364 sofia/external/22202 SOFIA DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:181 sofia/external/22202 Standard DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/external/22202) State DESTROY going to sleep


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 31 авг 2020 15:48 
FreeSWITCH_GuRu

Зарегистрирован: 22 авг 2012 09:52
Сообщения: 1667
с включенной записью
Код:
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.149237 [DEBUG] sofia_glue.c:1315 sofia/external/22202 sending invite version: 1.8.7 64bit
54db275f-7020-4a50-acf5-2d3287915d83 Local SDP:
54db275f-7020-4a50-acf5-2d3287915d83 v=0
54db275f-7020-4a50-acf5-2d3287915d83 o=FreeSWITCH 1598594104 1598594105 IN IP4 10.18.203.9
54db275f-7020-4a50-acf5-2d3287915d83 s=FreeSWITCH
54db275f-7020-4a50-acf5-2d3287915d83 c=IN IP4 10.18.203.9
54db275f-7020-4a50-acf5-2d3287915d83 t=0 0
54db275f-7020-4a50-acf5-2d3287915d83 m=audio 21912 RTP/AVP 9 101
54db275f-7020-4a50-acf5-2d3287915d83 a=rtpmap:9 G722/8000
54db275f-7020-4a50-acf5-2d3287915d83 a=rtpmap:101 telephone-event/8000
54db275f-7020-4a50-acf5-2d3287915d83 a=fmtp:101 0-16
54db275f-7020-4a50-acf5-2d3287915d83 a=ptime:20
54db275f-7020-4a50-acf5-2d3287915d83 a=sendrecv

шлюз не поддерживает G722 кодек и отвечает не по стандарту
54db275f-7020-4a50-acf5-2d3287915d83 2020-08-28 16:00:16.159237 [DEBUG] sofia.c:6494 Remote Reason: 57
но проблема изначально (скорей всего) в отсутствие альтернативных кодеков

с выключенной
Код:
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] sofia_glue.c:1618 sofia/external/22202 sending invite version: 1.10.5 64bit
d88ada98-aef6-4f37-a2c1-ca2beda10e5b Local SDP:
d88ada98-aef6-4f37-a2c1-ca2beda10e5b v=0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b o=FreeSWITCH 1598843725 1598843726 IN IP4 10.18.203.9
d88ada98-aef6-4f37-a2c1-ca2beda10e5b s=FreeSWITCH
d88ada98-aef6-4f37-a2c1-ca2beda10e5b c=IN IP4 10.18.203.9
d88ada98-aef6-4f37-a2c1-ca2beda10e5b t=0 0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b m=audio 30072 RTP/AVP 9 0 8 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:9 G722/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:0 PCMU/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:8 PCMA/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:101 telephone-event/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=fmtp:101 0-16
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=ptime:20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=sendrecv
d88ada98-aef6-4f37-a2c1-ca2beda10e5b

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


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 02 сен 2020 14:40 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 7
Да. Вы правы. Пока решил проблему исключив из согласования кодеков все кроме PCMA и PCMU.
Спасибо.
[+] код
<action application="export" data="nolocal:codec_string=PCMA,PCMU"/>


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 03 сен 2020 07:48 

Зарегистрирован: 28 авг 2020 10:30
Сообщения: 7
В общем рано радовался.
Соединение и запись длятся 10 секунд. Потом разъединение.

[+] лог
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.507463 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/22198@10.18.203.9:5060 [a0e43868-38d1-4305-9880-a6aa497546b4]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.517463 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_NEW (Cur 1 Tot 11)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.517463 [DEBUG] sofia.c:10280 sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.10.5 64bit
2020-09-03 07:23:13.517463 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 10.18.203.36:0.
2020-09-03 07:23:13.517463 [DEBUG] sofia.c:2434 detaching session a0e43868-38d1-4305-9880-a6aa497546b4
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.517463 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/22198@10.18.203.9:5060) State NEW
2020-09-03 07:23:13.527464 [DEBUG] sofia.c:2544 Re-attaching to session a0e43868-38d1-4305-9880-a6aa497546b4
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.537464 [DEBUG] sofia.c:10280 sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.10.5 64bit
2020-09-03 07:23:13.537464 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 10.18.203.36:0.
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.537464 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [received][100]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.537464 [DEBUG] sofia.c:7336 Remote SDP:
a0e43868-38d1-4305-9880-a6aa497546b4 v=0
a0e43868-38d1-4305-9880-a6aa497546b4 o=- 20201 20201 IN IP4 10.18.203.36
a0e43868-38d1-4305-9880-a6aa497546b4 s=SDP data
a0e43868-38d1-4305-9880-a6aa497546b4 c=IN IP4 10.18.203.36
a0e43868-38d1-4305-9880-a6aa497546b4 t=0 0
a0e43868-38d1-4305-9880-a6aa497546b4 m=audio 12182 RTP/AVP 9 0 8 18 101
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:9 G722/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:0 PCMU/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:8 PCMA/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:18 G729/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=fmtp:18 annexb=no
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:101 telephone-event/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=fmtp:101 0-15
a0e43868-38d1-4305-9880-a6aa497546b4 a=ptime:20
a0e43868-38d1-4305-9880-a6aa497546b4
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] sofia.c:7739 (sofia/internal/22198@10.18.203.9:5060) State Change CS_NEW -> CS_INIT
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_INIT (Cur 1 Tot 11)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/22198@10.18.203.9:5060) State INIT
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_sofia.c:93 sofia/internal/22198@10.18.203.9:5060 SOFIA INIT
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:40 sofia/internal/22198@10.18.203.9:5060 Standard INIT
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/22198@10.18.203.9:5060) State Change CS_INIT -> CS_ROUTING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/22198@10.18.203.9:5060) State INIT going to sleep
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_ROUTING (Cur 1 Tot 11)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_channel.c:2332 (sofia/internal/22198@10.18.203.9:5060) Callstate Change DOWN -> RINGING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/22198@10.18.203.9:5060) State ROUTING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_sofia.c:154 sofia/internal/22198@10.18.203.9:5060 SOFIA ROUTING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:236 sofia/internal/22198@10.18.203.9:5060 Standard ROUTING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [INFO] mod_dialplan_xml.c:637 Processing ТЕСТ <22198>->22202 in context office1
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->unloop] continue=false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [unloop] true() =~ /^true$/ break=on-false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->in-22198] continue=false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /^(22198)$/ break=on-false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->out-22198] continue=false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [out-22198] destination_number(22202) =~ /^(8\d{9,14})$/ break=on-false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 parsing [office1->gsv-22198] continue=false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Regex (PASS) [gsv-22198] destination_number(22202) =~ /^(22202)$/ break=on-false
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COPYRIGHT=(c) 2012)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_SOFTWARE=FreeSwitch)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_ARTIST=FreeSwitch)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COMMENT=Voice recordings)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_DATE=${strftime(%Y-%m-%d %H:%M)})
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_STEREO=true)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(media_bug_answer_req=true)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action record_session(C:/FreeSWITCH/recordings/${strftime(%Y-%m-%d-%H-%M-%S)}_${destination_number}_${caller_id_number}.wav)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action set(ringback=${us-ring})
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action export(nolocal:absolute_codec_string=PCMA,PCMU)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action bridge(sofia/gateway/TR22198/22202)
a0e43868-38d1-4305-9880-a6aa497546b4 Dialplan: sofia/internal/22198@10.18.203.9:5060 Action hangup()
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/22198@10.18.203.9:5060) State Change CS_ROUTING -> CS_EXECUTE
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/22198@10.18.203.9:5060) State ROUTING going to sleep
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_EXECUTE (Cur 1 Tot 11)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_sofia.c:209 sofia/internal/22198@10.18.203.9:5060 SOFIA EXECUTE
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] switch_core_state_machine.c:329 sofia/internal/22198@10.18.203.9:5060 Standard EXECUTE
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_COPYRIGHT=(c) 2012)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_COPYRIGHT]=[(c) 2012]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_SOFTWARE=FreeSwitch)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_SOFTWARE]=[FreeSwitch]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_ARTIST=FreeSwitch)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_ARTIST]=[FreeSwitch]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_COMMENT=Voice recordings)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_COMMENT]=[Voice recordings]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_DATE=2020-09-03 07:23)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.547465 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_DATE]=[2020-09-03 07:23]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(RECORD_STEREO=true)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [RECORD_STEREO]=[true]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(media_bug_answer_req=true)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [media_bug_answer_req]=[true]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_session.c:2722 Application record_session Requires media! pre_answering channel sofia/internal/22198@10.18.203.9:5060
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [INFO] switch_core_session.c:2724 Sending early media
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/22198@10.18.203.9:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_codec.c:111 sofia/internal/22198@10.18.203.9:5060 Original read codec set to PCMU:0
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:5911 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101 recv payload to 101
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/internal/22198@10.18.203.9:5060] 10.18.203.9 port 22444 -> 10.18.203.36 port 12182 codec: 0 ms: 20
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:8977 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:8984 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf receive payload to 101
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:9007 sofia/internal/22198@10.18.203.9:5060 Set rtp dtmf delay to 40
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/22198@10.18.203.9:5060!
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_channel.c:3565 (sofia/internal/22198@10.18.203.9:5060) Callstate Change RINGING -> EARLY
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/22198@10.18.203.9:5060.
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] mod_sofia.c:2593 Ring SDP:
a0e43868-38d1-4305-9880-a6aa497546b4 v=0
a0e43868-38d1-4305-9880-a6aa497546b4 o=FreeSWITCH 1599084549 1599084550 IN IP4 10.18.203.9
a0e43868-38d1-4305-9880-a6aa497546b4 s=FreeSWITCH
a0e43868-38d1-4305-9880-a6aa497546b4 c=IN IP4 10.18.203.9
a0e43868-38d1-4305-9880-a6aa497546b4 t=0 0
a0e43868-38d1-4305-9880-a6aa497546b4 m=audio 22444 RTP/AVP 0 101
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:0 PCMU/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:101 telephone-event/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=fmtp:101 0-16
a0e43868-38d1-4305-9880-a6aa497546b4 a=ptime:20
a0e43868-38d1-4305-9880-a6aa497546b4 a=sendrecv
a0e43868-38d1-4305-9880-a6aa497546b4
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.557466 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [early][183]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 record_session(C:/FreeSWITCH/recordings/2020-09-03-07-23-13_22202_22198.wav)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.597468 [DEBUG] switch_ivr_async.c:1351 Record session sample rate: 8000 -> 8000
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.597468 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/internal/22198@10.18.203.9:5060
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 set(ringback=%(2000,4000,440,480))
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.597468 [DEBUG] mod_dptools.c:1672 SET sofia/internal/22198@10.18.203.9:5060 [ringback]=[%(2000,4000,440,480)]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 export(nolocal:absolute_codec_string=PCMA,PCMU)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.597468 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) (REMOTE ONLY) [absolute_codec_string]=[PCMA,PCMU]
a0e43868-38d1-4305-9880-a6aa497546b4 EXECUTE [depth=0] sofia/internal/22198@10.18.203.9:5060 bridge(sofia/gateway/TR22198/22202)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.597468 [DEBUG] switch_channel.c:1264 sofia/internal/22198@10.18.203.9:5060 EXPORTING[export_vars] [absolute_codec_string]=[PCMA,PCMU] to event
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.597468 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [NOTICE] switch_channel.c:1118 New Channel sofia/external/22202 [418a306b-6c11-4f4a-a488-02a08e22416e]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] mod_sofia.c:5089 (sofia/external/22202) State Change CS_NEW -> CS_INIT
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_INIT (Cur 2 Tot 12)
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] switch_core_state_machine.c:628 (sofia/external/22202) State INIT
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] mod_sofia.c:93 sofia/external/22202 SOFIA INIT
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] sofia_glue.c:1618 sofia/external/22202 sending invite version: 1.10.5 64bit
418a306b-6c11-4f4a-a488-02a08e22416e Local SDP:
418a306b-6c11-4f4a-a488-02a08e22416e v=0
418a306b-6c11-4f4a-a488-02a08e22416e o=FreeSWITCH 1599077315 1599077316 IN IP4 10.18.203.9
418a306b-6c11-4f4a-a488-02a08e22416e s=FreeSWITCH
418a306b-6c11-4f4a-a488-02a08e22416e c=IN IP4 10.18.203.9
418a306b-6c11-4f4a-a488-02a08e22416e t=0 0
418a306b-6c11-4f4a-a488-02a08e22416e m=audio 29678 RTP/AVP 8 0 101
418a306b-6c11-4f4a-a488-02a08e22416e a=rtpmap:8 PCMA/8000
418a306b-6c11-4f4a-a488-02a08e22416e a=rtpmap:0 PCMU/8000
418a306b-6c11-4f4a-a488-02a08e22416e a=rtpmap:101 telephone-event/8000
418a306b-6c11-4f4a-a488-02a08e22416e a=fmtp:101 0-16
418a306b-6c11-4f4a-a488-02a08e22416e a=ptime:20
418a306b-6c11-4f4a-a488-02a08e22416e a=sendrecv
418a306b-6c11-4f4a-a488-02a08e22416e
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] switch_core_state_machine.c:40 sofia/external/22202 Standard INIT
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] switch_core_state_machine.c:48 (sofia/external/22202) State Change CS_INIT -> CS_ROUTING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.597468 [DEBUG] switch_core_state_machine.c:628 (sofia/external/22202) State INIT going to sleep
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_ROUTING (Cur 2 Tot 12)
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [calling][0]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] switch_core_state_machine.c:644 (sofia/external/22202) State ROUTING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] mod_sofia.c:154 sofia/external/22202 SOFIA ROUTING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] switch_ivr_originate.c:67 (sofia/external/22202) State Change CS_ROUTING -> CS_CONSUME_MEDIA
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] switch_core_state_machine.c:644 (sofia/external/22202) State ROUTING going to sleep
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 12)
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] switch_core_state_machine.c:663 (sofia/external/22202) State CONSUME_MEDIA
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] switch_core_state_machine.c:663 (sofia/external/22202) State CONSUME_MEDIA going to sleep
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.607468 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [calling][0]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.687473 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.687473 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
2020-09-03 07:23:13.707474 [INFO] sofia.c:1369 sofia/external/22202 Update Callee ID to "22202" <22202>
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [proceeding][180]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] sofia.c:7336 Remote SDP:
418a306b-6c11-4f4a-a488-02a08e22416e v=0
418a306b-6c11-4f4a-a488-02a08e22416e o=- 2805802 3577155 IN IP4 10.18.201.25
418a306b-6c11-4f4a-a488-02a08e22416e s=-
418a306b-6c11-4f4a-a488-02a08e22416e c=IN IP4 10.18.201.25
418a306b-6c11-4f4a-a488-02a08e22416e t=0 0
418a306b-6c11-4f4a-a488-02a08e22416e m=audio 20198 RTP/AVP 8
418a306b-6c11-4f4a-a488-02a08e22416e a=rtpmap:8 PCMA/8000
418a306b-6c11-4f4a-a488-02a08e22416e a=sendonly
418a306b-6c11-4f4a-a488-02a08e22416e a=ptime:20
418a306b-6c11-4f4a-a488-02a08e22416e
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [NOTICE] sofia.c:7339 Pre-Answer sofia/external/22202!
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_channel.c:3565 (sofia/external/22202) Callstate Change DOWN -> EARLY
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/22202 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_codec.c:111 sofia/external/22202 Original read codec set to PCMA:8
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:5882 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:5911 sofia/external/22202 Set 2833 dtmf send payload to 101 recv payload to 101
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/22202] 10.18.203.9 port 29678 -> 10.18.201.25 port 20198 codec: 8 ms: 20
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:8977 sofia/external/22202 Set 2833 dtmf send payload to 101
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:8984 sofia/external/22202 Set 2833 dtmf receive payload to 101
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.707474 [DEBUG] switch_core_media.c:9007 sofia/external/22202 Set rtp dtmf delay to 40
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:13.727475 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/22202]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.727475 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/22202) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.727475 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 12)
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.727475 [DEBUG] switch_core_state_machine.c:654 (sofia/external/22202) State EXCHANGE_MEDIA
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.727475 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:13.767478 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:15.547579 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [completing][200]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:15.547579 [DEBUG] sofia.c:7333 Duplicate SDP
418a306b-6c11-4f4a-a488-02a08e22416e v=0
418a306b-6c11-4f4a-a488-02a08e22416e o=- 2805802 3577155 IN IP4 10.18.201.25
418a306b-6c11-4f4a-a488-02a08e22416e s=-
418a306b-6c11-4f4a-a488-02a08e22416e c=IN IP4 10.18.201.25
418a306b-6c11-4f4a-a488-02a08e22416e t=0 0
418a306b-6c11-4f4a-a488-02a08e22416e m=audio 20198 RTP/AVP 8
418a306b-6c11-4f4a-a488-02a08e22416e a=rtpmap:8 PCMA/8000
418a306b-6c11-4f4a-a488-02a08e22416e a=sendonly
418a306b-6c11-4f4a-a488-02a08e22416e a=ptime:20
418a306b-6c11-4f4a-a488-02a08e22416e
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:15.547579 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [ready][200]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:15.547579 [NOTICE] sofia.c:8445 Channel [sofia/external/22202] has been answered
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:15.547579 [DEBUG] switch_channel.c:3865 (sofia/external/22202) Callstate Change EARLY -> ACTIVE
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.547579 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/22198@10.18.203.9:5060.
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.547579 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/22198@10.18.203.9:5060:
a0e43868-38d1-4305-9880-a6aa497546b4 v=0
a0e43868-38d1-4305-9880-a6aa497546b4 o=FreeSWITCH 1599084549 1599084551 IN IP4 10.18.203.9
a0e43868-38d1-4305-9880-a6aa497546b4 s=FreeSWITCH
a0e43868-38d1-4305-9880-a6aa497546b4 c=IN IP4 10.18.203.9
a0e43868-38d1-4305-9880-a6aa497546b4 t=0 0
a0e43868-38d1-4305-9880-a6aa497546b4 m=audio 22444 RTP/AVP 0 101
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:0 PCMU/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:101 telephone-event/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=fmtp:101 0-16
a0e43868-38d1-4305-9880-a6aa497546b4 a=ptime:20
a0e43868-38d1-4305-9880-a6aa497546b4 a=sendrecv
a0e43868-38d1-4305-9880-a6aa497546b4
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.547579 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [completed][200]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.547579 [NOTICE] switch_ivr_bridge.c:731 Channel [sofia/internal/22198@10.18.203.9:5060] has been answered
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.557580 [DEBUG] switch_channel.c:3865 (sofia/internal/22198@10.18.203.9:5060) Callstate Change EARLY -> ACTIVE
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.567580 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.567580 [DEBUG] switch_ivr_async.c:1640 No silence detection configured; assuming start of speech
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.587582 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [ready][200]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.607583 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [calling][0]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] sofia.c:7326 Channel sofia/internal/22198@10.18.203.9:5060 entering state [ready][200]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] sofia.c:7336 Remote SDP:
a0e43868-38d1-4305-9880-a6aa497546b4 v=0
a0e43868-38d1-4305-9880-a6aa497546b4 o=- 20201 20203 IN IP4 10.18.203.36
a0e43868-38d1-4305-9880-a6aa497546b4 s=SDP data
a0e43868-38d1-4305-9880-a6aa497546b4 c=IN IP4 10.18.203.36
a0e43868-38d1-4305-9880-a6aa497546b4 t=0 0
a0e43868-38d1-4305-9880-a6aa497546b4 m=audio 12182 RTP/AVP 0 101
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:0 PCMU/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=rtpmap:101 telephone-event/8000
a0e43868-38d1-4305-9880-a6aa497546b4 a=fmtp:101 0-15
a0e43868-38d1-4305-9880-a6aa497546b4 a=ptime:20
a0e43868-38d1-4305-9880-a6aa497546b4
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] switch_core_media.c:5911 sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101 recv payload to 101
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] sofia.c:8345 Processing updated SDP
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:15.627584 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/22198@10.18.203.9:5060.
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:15.867598 [INFO] switch_rtp.c:7719 Auto Changing audio port from 10.18.201.25:20198 to 10.18.203.19:12400
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.447260 [NOTICE] sofia.c:1089 Hangup sofia/internal/22198@10.18.203.9:5060 [CS_EXECUTE] [NORMAL_CLEARING]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.447260 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/22198@10.18.203.9:5060]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/22202]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/external/22202 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_ivr_bridge.c:1892 sofia/external/22202 skip receive message [UNBRIDGE] (channel is hungup already)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.447260 [DEBUG] switch_ivr_bridge.c:1895 sofia/internal/22198@10.18.203.9:5060 skip receive message [UNBRIDGE] (channel is hungup already)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.447260 [DEBUG] switch_core_session.c:2905 sofia/internal/22198@10.18.203.9:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE going to sleep
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_HANGUP (Cur 2 Tot 12)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.447260 [DEBUG] switch_ivr_async.c:1445 Stop recording file C:/FreeSWITCH/recordings/2020-09-03-07-23-13_22202_22198.wav
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:654 (sofia/external/22202) State EXCHANGE_MEDIA going to sleep
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_HANGUP (Cur 2 Tot 12)
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:848 (sofia/external/22202) Callstate Change ACTIVE -> HANGUP
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:850 (sofia/external/22202) State HANGUP
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] mod_sofia.c:447 sofia/external/22202 Overriding SIP cause 480 with 200 from the other leg
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] mod_sofia.c:453 Channel sofia/external/22202 hanging up, cause: NORMAL_CLEARING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/external/22202
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:60 sofia/external/22202 Standard HANGUP, cause: NORMAL_CLEARING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:850 (sofia/external/22202) State HANGUP going to sleep
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:620 (sofia/external/22202) State Change CS_HANGUP -> CS_REPORTING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_REPORTING (Cur 2 Tot 12)
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:936 (sofia/external/22202) State REPORTING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:174 sofia/external/22202 Standard REPORTING, cause: NORMAL_CLEARING
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:936 (sofia/external/22202) State REPORTING going to sleep
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:611 (sofia/external/22202) State Change CS_REPORTING -> CS_DESTROY
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_session.c:1726 Session 12 (sofia/external/22202) Locked, Waiting on external entities
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [NOTICE] switch_core_session.c:1744 Session 12 (sofia/external/22202) Ended
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/22202 [CS_DESTROY]
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:739 (sofia/external/22202) Running State Change CS_DESTROY (Cur 1 Tot 12)
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:749 (sofia/external/22202) State DESTROY
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] mod_sofia.c:364 sofia/external/22202 SOFIA DESTROY
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:181 sofia/external/22202 Standard DESTROY
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:27.447260 [DEBUG] switch_core_state_machine.c:749 (sofia/external/22202) State DESTROY going to sleep
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] switch_ivr_async.c:1520 Channel is hung up
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] switch_core_media_bug.c:1295 Removing BUG from sofia/internal/22198@10.18.203.9:5060
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/22198@10.18.203.9:5060) Callstate Change ACTIVE -> HANGUP
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/22198@10.18.203.9:5060) State HANGUP
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] mod_sofia.c:453 Channel sofia/internal/22198@10.18.203.9:5060 hanging up, cause: NORMAL_CLEARING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] switch_core_state_machine.c:60 sofia/internal/22198@10.18.203.9:5060 Standard HANGUP, cause: NORMAL_CLEARING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/22198@10.18.203.9:5060) State HANGUP going to sleep
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.457261 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/22198@10.18.203.9:5060) State Change CS_HANGUP -> CS_REPORTING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_REPORTING (Cur 1 Tot 12)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/22198@10.18.203.9:5060) State REPORTING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:174 sofia/internal/22198@10.18.203.9:5060 Standard REPORTING, cause: NORMAL_CLEARING
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/22198@10.18.203.9:5060) State REPORTING going to sleep
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/22198@10.18.203.9:5060) State Change CS_REPORTING -> CS_DESTROY
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_session.c:1726 Session 11 (sofia/internal/22198@10.18.203.9:5060) Locked, Waiting on external entities
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [NOTICE] switch_core_session.c:1744 Session 11 (sofia/internal/22198@10.18.203.9:5060) Ended
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/22198@10.18.203.9:5060 [CS_DESTROY]
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_DESTROY (Cur 0 Tot 12)
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/22198@10.18.203.9:5060) State DESTROY
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] mod_sofia.c:364 sofia/internal/22198@10.18.203.9:5060 SOFIA DESTROY
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:181 sofia/internal/22198@10.18.203.9:5060 Standard DESTROY
a0e43868-38d1-4305-9880-a6aa497546b4 2020-09-03 07:23:27.467261 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/22198@10.18.203.9:5060) State DESTROY going to sleep


Вернуться к началу
 Профиль  
 
 Заголовок сообщения: Re: Ошибка при записи разговоров
СообщениеДобавлено: 03 сен 2020 08:10 
FreeSWITCH_GuRu

Зарегистрирован: 22 авг 2012 09:52
Сообщения: 1667
Код:
418a306b-6c11-4f4a-a488-02a08e22416e 2020-09-03 07:23:15.867598 [INFO] switch_rtp.c:7719 Auto Changing audio port from 10.18.201.25:20198 to 10.18.203.19:12400

от шлюза RTP идет с адреса отличного от того что был в SDP
у вас там NAT в серых сетях зачем-то
ищите тут по темам что покрутить в профилях

проблема к записи отношения уже не имеет

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


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

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


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

Сейчас этот форум просматривают: Google [Bot] и гости: 12


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

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