freeswitch@node2.docker.rcsnet.ru> recv 1495 bytes from udp/[207.2.108.90]:5060 at 05:33:29.810186: ------------------------------------------------------------------------ INVITE sip:8432525@217.12.247.212 SIP/2.0 Via: SIP/2.0/UDP 207.2.108.90;rport;branch=z9hG4bKSg0UFmjvD2vpp Max-Forwards: 70 From: "" ;tag=5vva6tFU6Fe8F To: Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641941 INVITE Contact: User-Agent: Servpac VOIP v5 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 615 X-FS-Support: update_display,send_info Remote-Party-ID: ;party=calling;screen=yes;privacy=off v=0 o=Servpac 1521934982 1521934983 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=audio 21028 RTP/AVP 102 9 0 8 104 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 24210 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli ------------------------------------------------------------------------ send 350 bytes to udp/[207.2.108.90]:5060 at 05:33:29.810411: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKSg0UFmjvD2vpp From: "" ;tag=5vva6tFU6Fe8F To: Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641941 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Content-Length: 0 ------------------------------------------------------------------------ 2018-03-25 05:33:29.806063 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/safarov@207.2.108.90 [df128eb6-4b79-4dbd-ac6c-5715d0ed28d8] 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_NEW (Cur 1 Tot 2115) 2018-03-25 05:33:29.841048 [DEBUG] sofia.c:9873 sofia/internal/safarov@207.2.108.90 receiving invite from 207.2.108.90:5060 version: 1.6.20+git git b46d864 2018-01-27 11:52:21Z 64bit 2018-03-25 05:33:29.841048 [DEBUG] sofia.c:9989 IP 207.2.108.90 Approved by acl "domains[fs2@servpac.com]". Access Granted. 2018-03-25 05:33:29.841048 [DEBUG] sofia.c:10127 Authenticating user fs2@servpac.com 2018-03-25 05:33:29.841048 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [received][100] 2018-03-25 05:33:29.841048 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=Servpac 1521934982 1521934983 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=audio 21028 RTP/AVP 102 9 0 8 104 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 24210 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli 2018-03-25 05:33:29.841048 [DEBUG] sofia.c:7486 (sofia/internal/safarov@207.2.108.90) State Change CS_NEW -> CS_INIT 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/safarov@207.2.108.90) State NEW 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_INIT (Cur 1 Tot 2115) 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/safarov@207.2.108.90) State INIT 2018-03-25 05:33:29.841048 [DEBUG] mod_sofia.c:90 sofia/internal/safarov@207.2.108.90 SOFIA INIT 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:40 sofia/internal/safarov@207.2.108.90 Standard INIT 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/safarov@207.2.108.90) State Change CS_INIT -> CS_ROUTING 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/safarov@207.2.108.90) State INIT going to sleep 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_ROUTING (Cur 1 Tot 2115) 2018-03-25 05:33:29.841048 [DEBUG] switch_channel.c:2249 (sofia/internal/safarov@207.2.108.90) Callstate Change DOWN -> RINGING 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/safarov@207.2.108.90) State ROUTING 2018-03-25 05:33:29.841048 [DEBUG] mod_sofia.c:143 sofia/internal/safarov@207.2.108.90 SOFIA ROUTING 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:236 sofia/internal/safarov@207.2.108.90 Standard ROUTING 2018-03-25 05:33:29.841048 [INFO] mod_dialplan_xml.c:637 Processing ->8432525 in context servpac.com Dialplan: sofia/internal/safarov@207.2.108.90 parsing [servpac.com->number_conversion] continue=true Dialplan: sofia/internal/safarov@207.2.108.90 Regex (PASS) [number_conversion] destination_number(8432525) =~ /^(808)?(\d{7})$/ break=on-false Dialplan: sofia/internal/safarov@207.2.108.90 Action set_profile_var(destination_number=18088432525) INLINE EXECUTE sofia/internal/safarov@207.2.108.90 set_profile_var(destination_number=18088432525) Dialplan: sofia/internal/safarov@207.2.108.90 parsing [servpac.com->rxfax] continue=false Dialplan: sofia/internal/safarov@207.2.108.90 Regex (FAIL) [rxfax] destination_number(18088432525) =~ /^2000$/ break=on-false Dialplan: sofia/internal/safarov@207.2.108.90 parsing [servpac.com->txfax] continue=false Dialplan: sofia/internal/safarov@207.2.108.90 Regex (FAIL) [txfax] destination_number(18088432525) =~ /^2001$/ break=on-false Dialplan: sofia/internal/safarov@207.2.108.90 parsing [servpac.com->dial_DID_via_directory] continue=false Dialplan: sofia/internal/safarov@207.2.108.90 Regex (PASS) [dial_DID_via_directory] destination_number(18088432525) =~ /^1\d+$/ break=on-false Dialplan: sofia/internal/safarov@207.2.108.90 Action export(fax_enable_t38=true) Dialplan: sofia/internal/safarov@207.2.108.90 Action export(t38_passthru=true) Dialplan: sofia/internal/safarov@207.2.108.90 Action bridge(user/${user_data(${destination_number}@servpac.com attr id)}@servpac.com) 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/safarov@207.2.108.90) State Change CS_ROUTING -> CS_EXECUTE 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/safarov@207.2.108.90) State ROUTING going to sleep 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_EXECUTE (Cur 1 Tot 2115) 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/safarov@207.2.108.90) State EXECUTE 2018-03-25 05:33:29.841048 [DEBUG] mod_sofia.c:198 sofia/internal/safarov@207.2.108.90 SOFIA EXECUTE 2018-03-25 05:33:29.841048 [DEBUG] switch_core_state_machine.c:328 sofia/internal/safarov@207.2.108.90 Standard EXECUTE EXECUTE sofia/internal/safarov@207.2.108.90 export(fax_enable_t38=true) 2018-03-25 05:33:29.841048 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [fax_enable_t38]=[true] EXECUTE sofia/internal/safarov@207.2.108.90 export(t38_passthru=true) 2018-03-25 05:33:29.841048 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [t38_passthru]=[true] EXECUTE sofia/internal/safarov@207.2.108.90 bridge(user/mediant@servpac.com) 2018-03-25 05:33:29.841048 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [fax_enable_t38]=[true] to event 2018-03-25 05:33:29.841048 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [t38_passthru]=[true] to event 2018-03-25 05:33:29.841048 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-03-25 05:33:29.841048 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [fax_enable_t38]=[true] to event 2018-03-25 05:33:29.841048 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [t38_passthru]=[true] to event 2018-03-25 05:33:29.841048 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-03-25 05:33:29.841048 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-03-25 05:33:29.841048 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/18088432525@207.2.108.136 [b765264c-c804-4573-a4f8-fe16c92136e2] 2018-03-25 05:33:29.841048 [DEBUG] mod_sofia.c:4819 (sofia/internal/18088432525@207.2.108.136) State Change CS_NEW -> CS_INIT 2018-03-25 05:33:29.841048 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.108.136) Running State Change CS_INIT (Cur 2 Tot 2116) 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/18088432525@207.2.108.136) State INIT 2018-03-25 05:33:29.849116 [DEBUG] mod_sofia.c:90 sofia/internal/18088432525@207.2.108.136 SOFIA INIT 2018-03-25 05:33:29.849116 [DEBUG] sofia_glue.c:1295 sofia/internal/18088432525@207.2.108.136 sending invite version: 1.6.20+git git b46d864 2018-01-27 11:52:21Z 64bit Local SDP: v=0 o=FreeSWITCH 1521931905 1521931906 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 24104 RTP/AVP 102 9 0 8 104 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 26394 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli send 1573 bytes to udp/[207.2.108.136]:5060 at 05:33:29.850007: ------------------------------------------------------------------------ INVITE sip:18088432525@207.2.108.136 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK1yag5QvaZaFra Max-Forwards: 69 From: "" ;tag=r98pcjKBrQcjc To: Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 120641940 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 635 X-FS-Support: update_display,send_info Remote-Party-ID: ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1521931905 1521931906 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 24104 RTP/AVP 102 9 0 8 104 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 26394 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli ------------------------------------------------------------------------ 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:40 sofia/internal/18088432525@207.2.108.136 Standard INIT 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/18088432525@207.2.108.136) State Change CS_INIT -> CS_ROUTING 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/18088432525@207.2.108.136) State INIT going to sleep 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.108.136) Running State Change CS_ROUTING (Cur 2 Tot 2116) 2018-03-25 05:33:29.849116 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.108.136 entering state [calling][0] 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/18088432525@207.2.108.136) State ROUTING 2018-03-25 05:33:29.849116 [DEBUG] mod_sofia.c:143 sofia/internal/18088432525@207.2.108.136 SOFIA ROUTING 2018-03-25 05:33:29.849116 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/18088432525@207.2.108.136) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/18088432525@207.2.108.136) State ROUTING going to sleep 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.108.136) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 2116) 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/18088432525@207.2.108.136) State CONSUME_MEDIA 2018-03-25 05:33:29.849116 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/18088432525@207.2.108.136) State CONSUME_MEDIA going to sleep recv 319 bytes from udp/[207.2.108.136]:5060 at 05:33:30.103635: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK1yag5QvaZaFra;received=217.12.247.212 From: "" ;tag=r98pcjKBrQcjc To: Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 120641940 INVITE Server: YATE/5.3.1 Content-Length: 0 ------------------------------------------------------------------------ recv 489 bytes from udp/[207.2.108.136]:5060 at 05:33:31.698777: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK1yag5QvaZaFra;received=217.12.247.212 From: "" ;tag=r98pcjKBrQcjc To: ;tag=1647169070 Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 120641940 INVITE X-Channel: tr-local1/191;direction=outbound;mg=gw1 Server: YATE/5.3.1 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Length: 0 ------------------------------------------------------------------------ 2018-03-25 05:33:31.696069 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.108.136 entering state [proceeding][180] 2018-03-25 05:33:31.696069 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/18088432525@207.2.108.136! 2018-03-25 05:33:31.696069 [DEBUG] switch_channel.c:3346 (sofia/internal/18088432525@207.2.108.136) Callstate Change DOWN -> RINGING 2018-03-25 05:33:31.706056 [NOTICE] mod_sofia.c:2273 Ring-Ready sofia/internal/safarov@207.2.108.90! 2018-03-25 05:33:31.706056 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/internal/safarov@207.2.108.90! send 1045 bytes to udp/[207.2.108.90]:5060 at 05:33:31.712714: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKSg0UFmjvD2vpp From: "" ;tag=5vva6tFU6Fe8F To: ;tag=Q0FyaQ27tepZg Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641941 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Length: 0 X-FS-Display-Name: 18088432525 X-FS-Display-Number: sip:18088432525@217.12.247.212 X-Channel: tr-local1/191;direction=outbound;mg=gw1 X-FS-Support: update_display,send_info Remote-Party-ID: "18088432525" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2018-03-25 05:33:31.726069 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [early][180] freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> recv 701 bytes from udp/[207.2.108.136]:5060 at 05:33:34.540491: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK1yag5QvaZaFra;received=217.12.247.212 From: "" ;tag=r98pcjKBrQcjc To: ;tag=1647169070 Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 120641940 INVITE X-Channel: tr-local1/191;direction=outbound;mg=gw1 Server: YATE/5.3.1 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Type: application/sdp Content-Length: 184 v=0 o=yate 1521956014 1521956014 IN IP4 207.2.108.131 s=SIP Call c=IN IP4 207.2.108.131 t=0 0 m=audio 6580 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 ------------------------------------------------------------------------ 2018-03-25 05:33:34.536056 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.108.136 entering state [completing][200] 2018-03-25 05:33:34.536056 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=yate 1521956014 1521956014 IN IP4 207.2.108.131 s=SIP Call c=IN IP4 207.2.108.131 t=0 0 m=audio 6580 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 send 374 bytes to udp/[207.2.108.136]:5060 at 05:33:34.542140: ------------------------------------------------------------------------ ACK sip:18088432525@207.2.108.136:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK27386jDevK5ap Max-Forwards: 70 From: "" ;tag=r98pcjKBrQcjc To: ;tag=1647169070 Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 120641940 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2018-03-25 05:33:34.536056 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.108.136 entering state [ready][200] 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2] 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/18088432525@207.2.108.136 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2018-03-25 05:33:34.536056 [DEBUG] switch_core_codec.c:111 sofia/internal/18088432525@207.2.108.136 Original read codec set to PCMU:0 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:4767 sofia/internal/18088432525@207.2.108.136 Set 2833 dtmf send payload to 101 recv payload to 101 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/18088432525@207.2.108.136] 217.12.247.212 port 24104 -> 207.2.108.131 port 6580 codec: 0 ms: 20 2018-03-25 05:33:34.536056 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:7180 sofia/internal/18088432525@207.2.108.136 Set 2833 dtmf send payload to 101 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:7187 sofia/internal/18088432525@207.2.108.136 Set 2833 dtmf receive payload to 101 2018-03-25 05:33:34.536056 [DEBUG] switch_core_media.c:7210 sofia/internal/18088432525@207.2.108.136 Set rtp dtmf delay to 40 2018-03-25 05:33:34.536056 [NOTICE] sofia.c:8218 Channel [sofia/internal/18088432525@207.2.108.136] has been answered 2018-03-25 05:33:34.536056 [DEBUG] switch_channel.c:3773 (sofia/internal/18088432525@207.2.108.136) Callstate Change RINGING -> ACTIVE 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 104@48000 2018-03-25 05:33:34.546060 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps] 2018-03-25 05:33:34.546060 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/safarov@207.2.108.90 opus/48000 20 ms 960 samples 0 bits 1 channels 2018-03-25 05:33:34.546060 [DEBUG] switch_core_codec.c:111 sofia/internal/safarov@207.2.108.90 Original read codec set to opus:116 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 104@48000 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4767 sofia/internal/safarov@207.2.108.90 Set 2833 dtmf send payload to 104 recv payload to 104 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:103]/[VP8:99] 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:103] +++ is saved as a match 2018-03-25 05:33:34.546060 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2018-03-25 05:33:34.546060 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/safarov@207.2.108.90 VP8/90000 0 ms 2018-03-25 05:33:34.546060 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/safarov@207.2.108.90] 217.12.247.212 port 19396 -> 207.2.108.90 port 21028 codec: 102 ms: 20 2018-03-25 05:33:34.578749 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 960 bytes per 20ms 2018-03-25 05:33:34.578749 [DEBUG] switch_core_media.c:7180 sofia/internal/safarov@207.2.108.90 Set 2833 dtmf send payload to 104 2018-03-25 05:33:34.578749 [DEBUG] switch_core_media.c:7187 sofia/internal/safarov@207.2.108.90 Set 2833 dtmf receive payload to 104 2018-03-25 05:33:34.578749 [DEBUG] switch_core_media.c:7210 sofia/internal/safarov@207.2.108.90 Set rtp dtmf delay to 40 2018-03-25 05:33:34.578749 [DEBUG] switch_rtp.c:4152 Starting video timer. 2018-03-25 05:33:34.578749 [DEBUG] switch_core_media.c:7399 VIDEO RTP [sofia/internal/safarov@207.2.108.90] 217.12.247.212:27018->207.2.108.90:24210 codec: 103 ms: 0 [SUCCESS] 2018-03-25 05:33:34.578749 [DEBUG] switch_core_media.c:6003 sofia/internal/safarov@207.2.108.90 Starting Video thread 2018-03-25 05:33:34.586099 [DEBUG] switch_core_media.c:5869 sofia/internal/safarov@207.2.108.90 Video thread started. Echo is off 2018-03-25 05:33:34.586099 [INFO] switch_core_media.c:7479 Activating VIDEO RTCP PORT 24211 interval 1000 mux -1 2018-03-25 05:33:34.586099 [DEBUG] switch_rtp.c:4533 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 24211 2018-03-25 05:33:34.586099 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 207.2.108.90:24211 2 2018-03-25 05:33:34.586099 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/safarov@207.2.108.90! 2018-03-25 05:33:34.586099 [DEBUG] switch_channel.c:3474 (sofia/internal/safarov@207.2.108.90) Callstate Change RINGING -> EARLY 2018-03-25 05:33:34.586099 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-25 05:33:34.586099 [DEBUG] switch_core_media.c:7257 Video params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-25 05:33:34.586099 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/safarov@207.2.108.90: v=0 o=FreeSWITCH 1521936618 1521936619 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 19396 RTP/AVP 102 104 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; ptime=20; minptime=10; maxptime=40 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=ptime:20 a=sendrecv m=video 27018 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp:27019 IN IP4 217.12.247.212 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli 2018-03-25 05:33:34.586099 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/safarov@207.2.108.90] has been answered 2018-03-25 05:33:34.586099 [DEBUG] switch_channel.c:3773 (sofia/internal/safarov@207.2.108.90) Callstate Change EARLY -> ACTIVE 2018-03-25 05:33:34.586099 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/18088432525@207.2.108.136] 2018-03-25 05:33:34.586099 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/18088432525@207.2.108.136] send 1567 bytes to udp/[207.2.108.90]:5060 at 05:33:34.591510: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKSg0UFmjvD2vpp From: "" ;tag=5vva6tFU6Fe8F To: ;tag=Q0FyaQ27tepZg Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641941 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 485 X-FS-Display-Name: Outbound Call X-FS-Display-Number: sip:18088432525@217.12.247.212 X-Channel: tr-local1/191;direction=outbound;mg=gw1 X-FS-Support: update_display,send_info Remote-Party-ID: "Outbound Call" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1521936618 1521936619 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 19396 RTP/AVP 102 104 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; ptime=20; minptime=10; maxptime=40 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=ptime:20 m=video 27018 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp:27019 IN IP4 217.12.247.212 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli ------------------------------------------------------------------------ 2018-03-25 05:33:34.586099 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [completed][200] 2018-03-25 05:33:34.586099 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/18088432525@207.2.108.136) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2018-03-25 05:33:34.586099 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.108.136) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 2116) 2018-03-25 05:33:34.586099 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/18088432525@207.2.108.136) State EXCHANGE_MEDIA 2018-03-25 05:33:34.586099 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA 2018-03-25 05:33:34.596119 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 2018-03-25 05:33:34.596119 [NOTICE] switch_core_io.c:1202 Activating write resampler 2018-03-25 05:33:34.706056 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 352x288 1024 BW freeswitch@node2.docker.rcsnet.ru> recv 382 bytes from udp/[207.2.108.90]:5060 at 05:33:34.840599: ------------------------------------------------------------------------ ACK sip:8432525@217.12.247.212:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 207.2.108.90;rport;branch=z9hG4bK658X6KDa8Ny9c Max-Forwards: 70 From: "" ;tag=5vva6tFU6Fe8F To: ;tag=Q0FyaQ27tepZg Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641941 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2018-03-25 05:33:34.856057 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [ready][200] send 715 bytes to udp/[207.2.108.90]:5060 at 05:33:34.877501: ------------------------------------------------------------------------ INFO sip:mod_sofia@207.2.108.90:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK3gX18DyHSvUXH Max-Forwards: 70 From: ;tag=Q0FyaQ27tepZg To: "" ;tag=5vva6tFU6Fe8F Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641943 INFO Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Type: message/update_display Content-Length: 0 X-FS-Display-Name: Outbound Call X-FS-Display-Number: 18088432525 ------------------------------------------------------------------------ freeswitch@node2.docker.rcsnet.ru> 2018-03-25 05:33:34.966058 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: video ssrc[224970485] base_seq[28553] freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> 2018-03-25 05:33:35.088276 [DEBUG] switch_rtp.c:7308 Correct video ip/port confirmed. recv 458 bytes from udp/[207.2.108.90]:5060 at 05:33:35.142826: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK3gX18DyHSvUXH From: ;tag=Q0FyaQ27tepZg To: "" ;tag=5vva6tFU6Fe8F Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641943 INFO User-Agent: Servpac VOIP v5 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> 2018-03-25 05:33:38.926080 [DEBUG] switch_rtp.c:6758 Correct video RTCP ip/port confirmed. recv 629 bytes from udp/[207.2.108.136]:5060 at 05:33:40.398806: ------------------------------------------------------------------------ INVITE sip:mod_sofia@217.12.247.212:5060 SIP/2.0 Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b From: ;tag=1647169070 To: ;tag=r98pcjKBrQcjc Via: SIP/2.0/UDP 207.2.108.136:5060;rport;branch=z9hG4bK688773913 CSeq: 268496 INVITE User-Agent: YATE/5.3.1 Max-Forwards: 70 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Type: application/sdp Content-Length: 145 v=0 o=yate 1521956014 1521956015 IN IP4 207.2.108.131 s=SIP Call c=IN IP4 207.2.108.131 t=0 0 m=audio 6580 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ------------------------------------------------------------------------ send 363 bytes to udp/[207.2.108.136]:5060 at 05:33:40.398959: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 207.2.108.136:5060;rport=5060;branch=z9hG4bK688773913 From: ;tag=1647169070 To: ;tag=r98pcjKBrQcjc Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 268496 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Content-Length: 0 ------------------------------------------------------------------------ 2018-03-25 05:33:40.396062 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.108.136 entering state [received][100] 2018-03-25 05:33:40.396062 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=yate 1521956014 1521956015 IN IP4 207.2.108.131 s=SIP Call c=IN IP4 207.2.108.131 t=0 0 m=audio 6580 RTP/AVP 0 a=rtpmap:0 PCMU/8000 2018-03-25 05:33:40.396062 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2018-03-25 05:33:40.396062 [DEBUG] switch_core_media.c:4738 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event. 2018-03-25 05:33:40.396062 [DEBUG] switch_core_media.c:4767 sofia/internal/18088432525@207.2.108.136 Set 2833 dtmf send payload to 101 recv payload to 101 2018-03-25 05:33:40.396062 [DEBUG] sofia.c:8007 Reinvite resulted in codec negotiation failure. send 545 bytes to udp/[207.2.108.136]:5060 at 05:33:40.404573: ------------------------------------------------------------------------ SIP/2.0 488 Not Acceptable Here Via: SIP/2.0/UDP 207.2.108.136:5060;rport=5060;branch=z9hG4bK688773913 From: ;tag=1647169070 To: ;tag=r98pcjKBrQcjc Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 268496 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2018-03-25 05:33:40.396062 [DEBUG] sofia.c:7077 Channel sofia/internal/18088432525@207.2.108.136 skipping state [ready][488] recv 390 bytes from udp/[207.2.108.136]:5060 at 05:33:40.657369: ------------------------------------------------------------------------ ACK sip:mod_sofia@217.12.247.212:5060 SIP/2.0 Via: SIP/2.0/UDP 207.2.108.136:5060;rport;branch=z9hG4bK688773913 From: ;tag=1647169070 To: ;tag=r98pcjKBrQcjc Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 268496 ACK Max-Forwards: 70 Contact: User-Agent: YATE/5.3.1 Content-Length: 0 ------------------------------------------------------------------------ recv 731 bytes from udp/[207.2.108.136]:5060 at 05:33:40.703085: ------------------------------------------------------------------------ INVITE sip:mod_sofia@217.12.247.212:5060 SIP/2.0 Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b From: ;tag=1647169070 To: ;tag=r98pcjKBrQcjc Via: SIP/2.0/UDP 207.2.108.136:5060;rport;branch=z9hG4bK1501286775 CSeq: 268497 INVITE User-Agent: YATE/5.3.1 Max-Forwards: 70 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Type: application/sdp Content-Length: 246 v=0 o=yate 1521956014 1521956016 IN IP4 207.2.108.131 s=SIP Call c=IN IP4 207.2.108.131 t=0 0 m=image 6582 udptl t38 a=T38FaxVersion:0 a=T38FaxUdpEC:t38UDPRedundancy a=T38MaxBitRate:14400 a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 ------------------------------------------------------------------------ send 364 bytes to udp/[207.2.108.136]:5060 at 05:33:40.703255: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 207.2.108.136:5060;rport=5060;branch=z9hG4bK1501286775 From: ;tag=1647169070 To: ;tag=r98pcjKBrQcjc Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b CSeq: 268497 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Content-Length: 0 ------------------------------------------------------------------------ 2018-03-25 05:33:40.716096 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.108.136 entering state [received][100] 2018-03-25 05:33:40.716096 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=yate 1521956014 1521956016 IN IP4 207.2.108.131 s=SIP Call c=IN IP4 207.2.108.131 t=0 0 m=image 6582 udptl t38 a=T38FaxVersion:0 a=T38FaxUdpEC:t38UDPRedundancy a=T38MaxBitRate:14400 a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 2018-03-25 05:33:40.716096 [DEBUG] switch_core_media.c:4041 sofia/internal/18088432525@207.2.108.136 T38 ACCEPT on request 2018-03-25 05:33:40.716096 [DEBUG] switch_core_media.c:4103 Audio params changed for sofia/internal/18088432525@207.2.108.136 from 207.2.108.131:6580 to 207.2.108.131:6582 2018-03-25 05:33:40.716096 [DEBUG] switch_core_media.c:4131 Passing T38 req to other leg. v=0 o=yate 1521956014 1521956016 IN IP4 207.2.108.131 s=SIP Call c=IN IP4 207.2.108.131 t=0 0 m=image 6582 udptl t38 a=T38FaxVersion:0 a=T38FaxUdpEC:t38UDPRedundancy a=T38MaxBitRate:14400 a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 2018-03-25 05:33:40.716096 [DEBUG] switch_core_media.c:4146 sofia/internal/18088432525@207.2.108.136 T38 IS POSSIBLE on request 2018-03-25 05:33:40.736289 [DEBUG] switch_core_media.c:9390 sofia/internal/safarov@207.2.108.90 image media sdp: v=0 o=FreeSWITCH 1521936618 1521936620 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 19396 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-25 05:33:40.736289 [DEBUG] sofia_glue.c:1295 sofia/internal/safarov@207.2.108.90 sending invite version: 1.6.20+git git b46d864 2018-01-27 11:52:21Z 64bit Local SDP: v=0 o=FreeSWITCH 1521936618 1521936620 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 19396 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy send 982 bytes to udp/[207.2.108.90]:5060 at 05:33:40.737533: ------------------------------------------------------------------------ INVITE sip:mod_sofia@207.2.108.90:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK4Spta9eNp5HgD Max-Forwards: 70 From: ;tag=Q0FyaQ27tepZg To: "" ;tag=5vva6tFU6Fe8F Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a CSeq: 120641944 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Type: application/sdp Content-Length: 296 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1521936618 1521936620 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 19396 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------