freeswitchforum.com https://freeswitchforum.com/ |
|
Ошибка при записи разговоров https://freeswitchforum.com/viewtopic.php?f=6&t=1073 |
Страница 1 из 1 |
Автор: | Evgeniy [ 28 авг 2020 15:47 ] |
Заголовок сообщения: | Ошибка при записи разговоров |
Всем здравствуйте. При включении записи в диал плане внутреннего номера вызов с него на внешний gateway не проходит. В логах: [INFO] mod_dptools.c:3582 Originate Failed. Cause: BEARERCAPABILITY_NOTAUTH. С внешнего gateway вызов проходит и запись происходит. Где что я мог упустить? Заранее спасибо. |
Автор: | Evgeniy [ 28 авг 2020 18:37 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
Добавлю полный лог [+] лог 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 |
Автор: | Vlad1983 [ 28 авг 2020 19:10 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
лог с закомменченым record_session был бы не лишним |
Автор: | Evgeniy [ 29 авг 2020 15:24 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
Vlad1983 писал(а): лог с закомменченым record_session был бы не лишним Что вы имеете ввиду? Сейчас выходные. Собрал дома для теста. Та же ситуация. На внутренние номера запись тоже происходит. Сильно не ругаться. Только учусь. |
Автор: | Evgeniy [ 31 авг 2020 14:48 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
Лог с закоменченым 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 |
Автор: | Vlad1983 [ 31 авг 2020 15:48 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
с включенной записью Код: 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 |
Автор: | Evgeniy [ 02 сен 2020 14:40 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
Да. Вы правы. Пока решил проблему исключив из согласования кодеков все кроме PCMA и PCMU. Спасибо. [+] код <action application="export" data="nolocal:codec_string=PCMA,PCMU"/> |
Автор: | Evgeniy [ 03 сен 2020 07:48 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
В общем рано радовался. Соединение и запись длятся 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 |
Автор: | Vlad1983 [ 03 сен 2020 08:10 ] |
Заголовок сообщения: | Re: Ошибка при записи разговоров |
Код: 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 в серых сетях зачем-то ищите тут по темам что покрутить в профилях проблема к записи отношения уже не имеет |
Страница 1 из 1 | Часовой пояс: UTC + 4 часа |
Powered by phpBB® Forum Software © phpBB Group http://www.phpbb.com/ |