reeswitch@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 1503 bytes from udp/[207.2.108.90]:5060 at 20:44:15.936136: ------------------------------------------------------------------------ INVITE sip:18088432525@217.12.247.212 SIP/2.0 Via: SIP/2.0/UDP 207.2.108.90;rport;branch=z9hG4bKa944SjB27936D Max-Forwards: 70 From: "" ;tag=jp16tFyeQ9ZNm To: Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582864 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 1521809968 1521809969 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=audio 27888 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 31882 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 354 bytes to udp/[207.2.108.90]:5060 at 20:44:15.936390: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKa944SjB27936D From: "" ;tag=jp16tFyeQ9ZNm To: Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582864 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:15.936057 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/safarov@207.2.108.90 [0b24aca5-99a6-4b2a-8638-8786c295df67] 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_NEW (Cur 1 Tot 565) 2018-03-23 20:44:15.936057 [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-23 20:44:15.936057 [DEBUG] sofia.c:9989 IP 207.2.108.90 Approved by acl "domains[fs2@servpac.com]". Access Granted. 2018-03-23 20:44:15.936057 [DEBUG] sofia.c:10127 Authenticating user fs2@servpac.com 2018-03-23 20:44:15.936057 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [received][100] 2018-03-23 20:44:15.936057 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=Servpac 1521809968 1521809969 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=audio 27888 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 31882 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-23 20:44:15.936057 [DEBUG] sofia.c:7486 (sofia/internal/safarov@207.2.108.90) State Change CS_NEW -> CS_INIT 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/safarov@207.2.108.90) State NEW 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_INIT (Cur 1 Tot 565) 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/safarov@207.2.108.90) State INIT 2018-03-23 20:44:15.936057 [DEBUG] mod_sofia.c:90 sofia/internal/safarov@207.2.108.90 SOFIA INIT 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:40 sofia/internal/safarov@207.2.108.90 Standard INIT 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/safarov@207.2.108.90) State Change CS_INIT -> CS_ROUTING 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/safarov@207.2.108.90) State INIT going to sleep 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_ROUTING (Cur 1 Tot 565) 2018-03-23 20:44:15.936057 [DEBUG] switch_channel.c:2249 (sofia/internal/safarov@207.2.108.90) Callstate Change DOWN -> RINGING 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/safarov@207.2.108.90) State ROUTING 2018-03-23 20:44:15.936057 [DEBUG] mod_sofia.c:143 sofia/internal/safarov@207.2.108.90 SOFIA ROUTING 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:236 sofia/internal/safarov@207.2.108.90 Standard ROUTING 2018-03-23 20:44:15.936057 [INFO] mod_dialplan_xml.c:637 Processing ->18088432525 in context servpac.com 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->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-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/safarov@207.2.108.90) State Change CS_ROUTING -> CS_EXECUTE 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/safarov@207.2.108.90) State ROUTING going to sleep 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_EXECUTE (Cur 1 Tot 565) 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/safarov@207.2.108.90) State EXECUTE 2018-03-23 20:44:15.936057 [DEBUG] mod_sofia.c:198 sofia/internal/safarov@207.2.108.90 SOFIA EXECUTE 2018-03-23 20:44:15.936057 [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-23 20:44:15.936057 [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-23 20:44:15.936057 [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-23 20:44:15.936057 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [fax_enable_t38]=[true] to event 2018-03-23 20:44:15.936057 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [t38_passthru]=[true] to event 2018-03-23 20:44:15.936057 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-03-23 20:44:15.936057 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [fax_enable_t38]=[true] to event 2018-03-23 20:44:15.936057 [DEBUG] switch_channel.c:1250 sofia/internal/safarov@207.2.108.90 EXPORTING[export_vars] [t38_passthru]=[true] to event 2018-03-23 20:44:15.936057 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-03-23 20:44:15.936057 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-03-23 20:44:15.936057 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/18088432525@207.2.106.136 [77477746-0a40-43a0-a3b8-261492a93658] 2018-03-23 20:44:15.936057 [DEBUG] mod_sofia.c:4819 (sofia/internal/18088432525@207.2.106.136) State Change CS_NEW -> CS_INIT 2018-03-23 20:44:15.936057 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.106.136) Running State Change CS_INIT (Cur 2 Tot 566) 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/18088432525@207.2.106.136) State INIT 2018-03-23 20:44:15.936057 [DEBUG] mod_sofia.c:90 sofia/internal/18088432525@207.2.106.136 SOFIA INIT 2018-03-23 20:44:15.936057 [DEBUG] sofia_glue.c:1295 sofia/internal/18088432525@207.2.106.136 sending invite version: 1.6.20+git git b46d864 2018-01-27 11:52:21Z 64bit Local SDP: v=0 o=FreeSWITCH 1521818923 1521818924 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 18932 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 25940 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-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:40 sofia/internal/18088432525@207.2.106.136 Standard INIT 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/18088432525@207.2.106.136) State Change CS_INIT -> CS_ROUTING 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/18088432525@207.2.106.136) State INIT going to sleep 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.106.136) Running State Change CS_ROUTING (Cur 2 Tot 566) 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/18088432525@207.2.106.136) State ROUTING 2018-03-23 20:44:15.936057 [DEBUG] mod_sofia.c:143 sofia/internal/18088432525@207.2.106.136 SOFIA ROUTING 2018-03-23 20:44:15.936057 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/18088432525@207.2.106.136) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/18088432525@207.2.106.136) State ROUTING going to sleep 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.106.136) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 566) 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/18088432525@207.2.106.136) State CONSUME_MEDIA 2018-03-23 20:44:15.936057 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/18088432525@207.2.106.136) State CONSUME_MEDIA going to sleep send 1573 bytes to udp/[207.2.106.136]:5060 at 20:44:15.945176: ------------------------------------------------------------------------ INVITE sip:18088432525@207.2.106.136 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bKSK5Xct4UBc2ZN Max-Forwards: 69 From: "" ;tag=pXpmZjUja5DmS To: Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582863 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 1521818923 1521818924 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 18932 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 25940 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-23 20:44:15.946268 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [calling][0] recv 319 bytes from udp/[207.2.106.136]:5060 at 20:44:16.212314: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKSK5Xct4UBc2ZN;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582863 INVITE Server: YATE/4.2.1 Content-Length: 0 ------------------------------------------------------------------------ recv 488 bytes from udp/[207.2.106.136]:5060 at 20:44:17.478818: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKSK5Xct4UBc2ZN;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582863 INVITE X-Channel: tr-local2/240;direction=outbound;mg=gw2 Server: YATE/4.2.1 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:17.476063 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [proceeding][180] 2018-03-23 20:44:17.476063 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/18088432525@207.2.106.136! 2018-03-23 20:44:17.476063 [DEBUG] switch_channel.c:3346 (sofia/internal/18088432525@207.2.106.136) Callstate Change DOWN -> RINGING 2018-03-23 20:44:17.496095 [NOTICE] mod_sofia.c:2273 Ring-Ready sofia/internal/safarov@207.2.108.90! 2018-03-23 20:44:17.496095 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/internal/safarov@207.2.108.90! send 1053 bytes to udp/[207.2.108.90]:5060 at 20:44:17.497757: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKa944SjB27936D From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582864 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-local2/240;direction=outbound;mg=gw2 X-FS-Support: update_display,send_info Remote-Party-ID: "18088432525" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2018-03-23 20:44:17.516165 [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> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> recv 700 bytes from udp/[207.2.106.136]:5060 at 20:44:19.134090: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKSK5Xct4UBc2ZN;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582863 INVITE X-Channel: tr-local2/240;direction=outbound;mg=gw2 Server: YATE/4.2.1 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Type: application/sdp Content-Length: 184 v=0 o=yate 1521837859 1521837859 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=audio 6550 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 ------------------------------------------------------------------------ 2018-03-23 20:44:19.126061 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [completing][200] 2018-03-23 20:44:19.126061 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=yate 1521837859 1521837859 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=audio 6550 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 send 373 bytes to udp/[207.2.106.136]:5060 at 20:44:19.135662: ------------------------------------------------------------------------ ACK sip:18088432525@207.2.106.136:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bKtvypeNNZ8mrjH Max-Forwards: 70 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582863 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:19.126061 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [ready][200] 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2] 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/18088432525@207.2.106.136 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2018-03-23 20:44:19.126061 [DEBUG] switch_core_codec.c:111 sofia/internal/18088432525@207.2.106.136 Original read codec set to PCMU:0 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:4767 sofia/internal/18088432525@207.2.106.136 Set 2833 dtmf send payload to 101 recv payload to 101 2018-03-23 20:44:19.126061 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/18088432525@207.2.106.136] 217.12.247.212 port 18932 -> 207.2.108.132 port 6550 codec: 0 ms: 20 2018-03-23 20:44:19.136466 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:7180 sofia/internal/18088432525@207.2.106.136 Set 2833 dtmf send payload to 101 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:7187 sofia/internal/18088432525@207.2.106.136 Set 2833 dtmf receive payload to 101 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:7210 sofia/internal/18088432525@207.2.106.136 Set rtp dtmf delay to 40 2018-03-23 20:44:19.136466 [NOTICE] sofia.c:8218 Channel [sofia/internal/18088432525@207.2.106.136] has been answered 2018-03-23 20:44:19.136466 [DEBUG] switch_channel.c:3773 (sofia/internal/18088432525@207.2.106.136) Callstate Change RINGING -> ACTIVE 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 104@48000 2018-03-23 20:44:19.136466 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps] 2018-03-23 20:44:19.136466 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps] 2018-03-23 20:44:19.136466 [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-23 20:44:19.136466 [DEBUG] switch_core_codec.c:111 sofia/internal/safarov@207.2.108.90 Original read codec set to opus:116 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 104@48000 2018-03-23 20:44:19.136466 [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-23 20:44:19.136466 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:103]/[VP8:99] 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:103] +++ is saved as a match 2018-03-23 20:44:19.136466 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2018-03-23 20:44:19.136466 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/safarov@207.2.108.90 VP8/90000 0 ms 2018-03-23 20:44:19.136466 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/safarov@207.2.108.90] 217.12.247.212 port 16764 -> 207.2.108.90 port 27888 codec: 102 ms: 20 2018-03-23 20:44:19.146186 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 960 bytes per 20ms 2018-03-23 20:44:19.146186 [DEBUG] switch_core_media.c:7180 sofia/internal/safarov@207.2.108.90 Set 2833 dtmf send payload to 104 2018-03-23 20:44:19.146186 [DEBUG] switch_core_media.c:7187 sofia/internal/safarov@207.2.108.90 Set 2833 dtmf receive payload to 104 2018-03-23 20:44:19.146186 [DEBUG] switch_core_media.c:7210 sofia/internal/safarov@207.2.108.90 Set rtp dtmf delay to 40 2018-03-23 20:44:19.146186 [DEBUG] switch_rtp.c:4152 Starting video timer. 2018-03-23 20:44:19.146186 [DEBUG] switch_core_media.c:7399 VIDEO RTP [sofia/internal/safarov@207.2.108.90] 217.12.247.212:24042->207.2.108.90:31882 codec: 103 ms: 0 [SUCCESS] 2018-03-23 20:44:19.146186 [DEBUG] switch_core_media.c:6003 sofia/internal/safarov@207.2.108.90 Starting Video thread 2018-03-23 20:44:19.167210 [DEBUG] switch_core_media.c:5869 sofia/internal/safarov@207.2.108.90 Video thread started. Echo is off 2018-03-23 20:44:19.167210 [INFO] switch_core_media.c:7479 Activating VIDEO RTCP PORT 31883 interval 1000 mux -1 2018-03-23 20:44:19.167210 [DEBUG] switch_rtp.c:4533 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 31883 2018-03-23 20:44:19.167210 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 207.2.108.90:31883 2 2018-03-23 20:44:19.167210 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/safarov@207.2.108.90! 2018-03-23 20:44:19.167210 [DEBUG] switch_channel.c:3474 (sofia/internal/safarov@207.2.108.90) Callstate Change RINGING -> EARLY 2018-03-23 20:44:19.167210 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:19.167210 [DEBUG] switch_core_media.c:7257 Video params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:19.167210 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/safarov@207.2.108.90: v=0 o=FreeSWITCH 1521821095 1521821096 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 16764 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 24042 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp:24043 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-23 20:44:19.167210 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/safarov@207.2.108.90] has been answered 2018-03-23 20:44:19.167210 [DEBUG] switch_channel.c:3773 (sofia/internal/safarov@207.2.108.90) Callstate Change EARLY -> ACTIVE 2018-03-23 20:44:19.167210 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/18088432525@207.2.106.136] 2018-03-23 20:44:19.167210 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/18088432525@207.2.106.136] 2018-03-23 20:44:19.167210 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/18088432525@207.2.106.136) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA send 1575 bytes to udp/[207.2.108.90]:5060 at 20:44:19.172362: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKa944SjB27936D From: "" ;tag=jp16tFyeQ9ZNm freeswitch@node2.docker.rcsnet.ru> To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582864 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-local2/240;direction=outbound;mg=gw2 X-FS-Support: update_display,send_info Remote-Party-ID: "Outbound Call" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1521821095 1521821096 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=audio 16764 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 24042 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp:24043 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-23 20:44:19.167210 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.106.136) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 566) 2018-03-23 20:44:19.167210 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/18088432525@207.2.106.136) State EXCHANGE_MEDIA 2018-03-23 20:44:19.167210 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA 2018-03-23 20:44:19.167210 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [completed][200] 2018-03-23 20:44:19.196114 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 2018-03-23 20:44:19.196114 [NOTICE] switch_core_io.c:1202 Activating write resampler freeswitch@node2.docker.rcsnet.ru> 2018-03-23 20:44:19.286058 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 352x288 1024 BW freeswitch@node2.docker.rcsnet.ru> recv 390 bytes from udp/[207.2.108.90]:5060 at 20:44:19.421563: ------------------------------------------------------------------------ ACK sip:18088432525@217.12.247.212:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 207.2.108.90;rport;branch=z9hG4bKBjyXUDv54jtSS Max-Forwards: 70 From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582864 ACK Contact: Content-Length: 0 freeswitch@node2.docker.rcsnet.ru> ------------------------------------------------------------------------ 2018-03-23 20:44:19.416057 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [ready][200] send 723 bytes to udp/[207.2.108.90]:5060 at 20:44:19.446150: ------------------------------------------------------------------------ INFO sip:mod_sofia@207.2.108.90:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bKU5QFgg625Xe5c Max-Forwards: 70 From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582865 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 ------------------------------------------------------------------------ 2018-03-23 20:44:19.546058 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: video ssrc[3915896976] base_seq[40201] 2018-03-23 20:44:19.666675 [DEBUG] switch_rtp.c:7308 Correct video ip/port confirmed. recv 462 bytes from udp/[207.2.108.90]:5060 at 20:44:19.706229: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKU5QFgg625Xe5c From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582865 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 ------------------------------------------------------------------------ 2018-03-23 20:44:19.756058 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 2018-03-23 20:44:19.756058 [NOTICE] switch_core_io.c:1202 Activating write resampler 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> recv 1034 bytes from udp/[207.2.108.90]:5060 at 20:44:21.685733: ------------------------------------------------------------------------ INVITE sip:18088432525@217.12.247.212:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 207.2.108.90;rport;branch=z9hG4bKeDa80yegvDXHc Max-Forwards: 70 From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582865 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 Content-Type: application/sdp Content-Length: 310 X-FS-Support: update_display,send_info Remote-Party-ID: ;party=calling;screen=yes;privacy=off v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ send 372 bytes to udp/[207.2.108.90]:5060 at 20:44:21.685931: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKeDa80yegvDXHc From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582865 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:21.696250 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [received][100] 2018-03-23 20:44:21.696250 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:21.696250 [DEBUG] switch_core_media.c:4041 sofia/internal/safarov@207.2.108.90 T38 ACCEPT on request 2018-03-23 20:44:21.696250 [DEBUG] switch_core_media.c:4098 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:21.696250 [DEBUG] switch_core_media.c:4131 Passing T38 req to other leg. v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:21.696250 [DEBUG] switch_core_media.c:4146 sofia/internal/safarov@207.2.108.90 T38 IS POSSIBLE on request 2018-03-23 20:44:21.716062 [DEBUG] switch_core_media.c:9390 sofia/internal/18088432525@207.2.106.136 image media sdp: v=0 o=FreeSWITCH 1521818923 1521818925 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:21.716062 [DEBUG] sofia_glue.c:1295 sofia/internal/18088432525@207.2.106.136 sending invite version: 1.6.20+git git b46d864 2018-01-27 11:52:21Z 64bit Local SDP: v=0 o=FreeSWITCH 1521818923 1521818925 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy send 1076 bytes to udp/[207.2.106.136]:5060 at 20:44:21.717499: ------------------------------------------------------------------------ INVITE sip:18088432525@207.2.106.136:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bKveH8HBQ6264Qr Max-Forwards: 69 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582864 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: 320 X-FS-Support: update_display,send_info Remote-Party-ID: ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1521818923 1521818925 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:21.716062 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [calling][0] freeswitch@node2.docker.rcsnet.ru> freeswitch@node2.docker.rcsnet.ru> recv 333 bytes from udp/[207.2.106.136]:5060 at 20:44:21.982437: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKveH8HBQ6264Qr;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582864 INVITE Server: YATE/4.2.1 Content-Length: 0 ------------------------------------------------------------------------ recv 773 bytes from udp/[207.2.106.136]:5060 at 20:44:22.024253: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKveH8HBQ6264Qr;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582864 INVITE Server: YATE/4.2.1 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Type: application/sdp Content-Length: 309 v=0 o=yate 1521837859 1521837860 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ freeswitch@node2.docker.rcsnet.ru> 2018-03-23 20:44:22.046234 [DEBUG] switch_core_media.c:6291 AUDIO RTP CHANGING DEST TO: [207.2.108.132:6552] 2018-03-23 20:44:22.046234 [DEBUG] sofia.c:6654 Passing 200 OK to other leg v=0 o=yate 1521837859 1521837860 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:22.046234 [DEBUG] switch_core_media.c:653 Audio params are unchanged for sofia/internal/18088432525@207.2.106.136. 2018-03-23 20:44:22.046234 [INFO] sofia.c:6704 Activating T38 Passthru 2018-03-23 20:44:22.046234 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [completing][200] 2018-03-23 20:44:22.046234 [DEBUG] sofia.c:7091 Duplicate SDP v=0 o=yate 1521837859 1521837860 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy send 373 bytes to udp/[207.2.106.136]:5060 at 20:44:22.051399: ------------------------------------------------------------------------ ACK sip:18088432525@207.2.106.136:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bKXQa1K679ZFUam Max-Forwards: 70 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582864 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:22.046234 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [ready][200] 2018-03-23 20:44:22.046234 [DEBUG] switch_core_media.c:4041 sofia/internal/18088432525@207.2.106.136 T38 ACCEPT on response 2018-03-23 20:44:22.046234 [DEBUG] switch_core_media.c:4146 sofia/internal/18088432525@207.2.106.136 T38 IS POSSIBLE on response 2018-03-23 20:44:22.046234 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/18088432525@207.2.106.136. 2018-03-23 20:44:22.056057 [DEBUG] mod_sofia.c:2132 Responding with 200 [OK] 2018-03-23 20:44:22.056057 [DEBUG] switch_core_media.c:9390 sofia/internal/safarov@207.2.108.90 image media sdp: v=0 o=FreeSWITCH 1521821095 1521821097 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy send 1056 bytes to udp/[207.2.108.90]:5060 at 20:44:22.066180: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bKeDa80yegvDXHc From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582865 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 Content-Type: application/sdp Content-Disposition: session Content-Length: 342 X-Channel: tr-local2/240;direction=outbound;mg=gw2 v=0 o=FreeSWITCH 1521821095 1521821097 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy m=video 0 RTP/AVP 19 ------------------------------------------------------------------------ 2018-03-23 20:44:22.066287 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [completed][200] freeswitch@node2.docker.rcsnet.ru> recv 390 bytes from udp/[207.2.108.90]:5060 at 20:44:22.326391: ------------------------------------------------------------------------ ACK sip:18088432525@217.12.247.212:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 207.2.108.90;rport;branch=z9hG4bKFp302SZKSpK4Q Max-Forwards: 70 From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582865 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:22.326058 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [ready][200] freeswitch@node2.docker.rcsnet.ru> 2018-03-23 20:44:22.486064 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 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-23 20:44:23.526063 [DEBUG] switch_rtp.c:6758 Correct video RTCP ip/port confirmed. recv 792 bytes from udp/[207.2.106.136]:5060 at 20:44:25.071452: ------------------------------------------------------------------------ INVITE sip:mod_sofia@217.12.247.212:5060 SIP/2.0 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b From: ;tag=595902636 To: ;tag=pXpmZjUja5DmS Via: SIP/2.0/UDP 207.2.106.136:5060;rport;branch=z9hG4bK1345264137 CSeq: 13336 INVITE User-Agent: YATE/4.2.1 Max-Forwards: 70 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Type: application/sdp Content-Length: 309 v=0 o=yate 1521837859 1521837861 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ send 362 bytes to udp/[207.2.106.136]:5060 at 20:44:25.071609: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 207.2.106.136:5060;rport=5060;branch=z9hG4bK1345264137 From: ;tag=595902636 To: ;tag=pXpmZjUja5DmS Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 13336 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.20+git-20180127T115221Z~b46d864f14~64bit Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:25.066067 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [received][100] 2018-03-23 20:44:25.066067 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=yate 1521837859 1521837861 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.066067 [DEBUG] switch_core_media.c:4041 sofia/internal/18088432525@207.2.106.136 T38 ACCEPT on request 2018-03-23 20:44:25.066067 [DEBUG] switch_core_media.c:4098 Audio params are unchanged for sofia/internal/18088432525@207.2.106.136. 2018-03-23 20:44:25.066067 [DEBUG] switch_core_media.c:4131 Passing T38 req to other leg. v=0 o=yate 1521837859 1521837861 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.066067 [DEBUG] switch_core_media.c:4146 sofia/internal/18088432525@207.2.106.136 T38 IS POSSIBLE on request 2018-03-23 20:44:25.066067 [DEBUG] switch_core_media.c:9390 sofia/internal/safarov@207.2.108.90 image media sdp: v=0 o=FreeSWITCH 1521821095 1521821098 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.066067 [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 1521821095 1521821098 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy send 1014 bytes to udp/[207.2.108.90]:5060 at 20:44:25.072950: ------------------------------------------------------------------------ INVITE sip:mod_sofia@207.2.108.90:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bKy03SN1rDXrHXF Max-Forwards: 70 From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582866 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: 320 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1521821095 1521821098 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:25.066067 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [calling][0] recv 324 bytes from udp/[207.2.108.90]:5060 at 20:44:25.321089: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKy03SN1rDXrHXF From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582866 INVITE User-Agent: Servpac VOIP v5 Content-Length: 0 ------------------------------------------------------------------------ recv 851 bytes from udp/[207.2.108.90]:5060 at 20:44:25.322179: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bKy03SN1rDXrHXF From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582866 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 Content-Type: application/sdp Content-Length: 310 v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:25.316055 [INFO] sofia.c:1268 sofia/internal/safarov@207.2.108.90 Update Caller ID to "safarov" 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:6245 VIDEO RTP CHANGING DEST TO: [207.2.108.90:31882] 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:6269 Remote address:port [207.2.108.90:27888] has not changed. 2018-03-23 20:44:25.316055 [DEBUG] sofia.c:6654 Passing 200 OK to other leg v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:653 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:25.316055 [INFO] sofia.c:6704 Activating T38 Passthru 2018-03-23 20:44:25.316055 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [completing][200] 2018-03-23 20:44:25.316055 [DEBUG] sofia.c:7091 Duplicate SDP v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.316055 [DEBUG] mod_sofia.c:2132 Responding with 200 [OK] 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:9390 sofia/internal/18088432525@207.2.106.136 image media sdp: v=0 o=FreeSWITCH 1521818923 1521818926 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy send 330 bytes to udp/[207.2.108.90]:5060 at 20:44:25.324239: ------------------------------------------------------------------------ ACK sip:mod_sofia@207.2.108.90:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bKZ9vjQv9gt17FB Max-Forwards: 70 From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582866 ACK Content-Length: 0 ------------------------------------------------------------------------ send 953 bytes to udp/[207.2.106.136]:5060 at 20:44:25.324328: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 207.2.106.136:5060;rport=5060;branch=z9hG4bK1345264137 From: ;tag=595902636 To: ;tag=pXpmZjUja5DmS Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 13336 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: 320 X-Channel: tr-local2/240;direction=outbound;mg=gw2 v=0 o=FreeSWITCH 1521818923 1521818926 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:25.316055 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [ready][200] 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:4041 sofia/internal/safarov@207.2.108.90 T38 ACCEPT on response 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:4098 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:4131 Passing T38 req to other leg. v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:4146 sofia/internal/safarov@207.2.108.90 T38 IS POSSIBLE on response 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:7257 Video params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:25.316055 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [completed][200] 2018-03-23 20:44:25.316055 [DEBUG] switch_core_media.c:9390 sofia/internal/18088432525@207.2.106.136 image media sdp: v=0 o=FreeSWITCH 1521818923 1521818927 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.316055 [DEBUG] sofia_glue.c:1295 sofia/internal/18088432525@207.2.106.136 sending invite version: 1.6.20+git git b46d864 2018-01-27 11:52:21Z 64bit Local SDP: v=0 o=FreeSWITCH 1521818923 1521818927 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy send 1074 bytes to udp/[207.2.106.136]:5060 at 20:44:25.325679: ------------------------------------------------------------------------ INVITE sip:mod_sofia@207.2.106.136:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK0jpBSQtmQay2p Max-Forwards: 69 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582865 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: 320 X-FS-Support: update_display,send_info Remote-Party-ID: ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1521818923 1521818927 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:25.326393 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [calling][0] 2018-03-23 20:44:25.406061 [DEBUG] switch_rtp.c:7308 Correct video ip/port confirmed. recv 389 bytes from udp/[207.2.106.136]:5060 at 20:44:25.586573: ------------------------------------------------------------------------ ACK sip:mod_sofia@217.12.247.212:5060 SIP/2.0 Via: SIP/2.0/UDP 207.2.106.136:5060;rport;branch=z9hG4bK1185259264 From: ;tag=595902636 To: ;tag=pXpmZjUja5DmS Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 13336 ACK Max-Forwards: 70 Contact: User-Agent: YATE/4.2.1 Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:25.586068 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [ready][200] 2018-03-23 20:44:25.586068 [DEBUG] switch_core_media.c:4041 sofia/internal/18088432525@207.2.106.136 T38 ACCEPT on response 2018-03-23 20:44:25.586068 [DEBUG] switch_core_media.c:4098 Audio params are unchanged for sofia/internal/18088432525@207.2.106.136. 2018-03-23 20:44:25.586068 [DEBUG] switch_core_media.c:4131 Passing T38 req to other leg. v=0 o=yate 1521837859 1521837861 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.586068 [DEBUG] switch_core_media.c:4146 sofia/internal/18088432525@207.2.106.136 T38 IS POSSIBLE on response 2018-03-23 20:44:25.586068 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/18088432525@207.2.106.136. 2018-03-23 20:44:25.586068 [DEBUG] switch_core_media.c:9390 sofia/internal/safarov@207.2.108.90 image media sdp: v=0 o=FreeSWITCH 1521821095 1521821099 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.586068 [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 1521821095 1521821099 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy send 1014 bytes to udp/[207.2.108.90]:5060 at 20:44:25.587746: ------------------------------------------------------------------------ INVITE sip:mod_sofia@207.2.108.90:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK1UF4tjBrmKmNj Max-Forwards: 70 From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582867 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: 320 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1521821095 1521821099 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1024 a=T38FaxMaxDatagram:238 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:25.586068 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [calling][0] recv 333 bytes from udp/[207.2.106.136]:5060 at 20:44:25.591705: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK0jpBSQtmQay2p;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582865 INVITE Server: YATE/4.2.1 Content-Length: 0 ------------------------------------------------------------------------ recv 771 bytes from udp/[207.2.106.136]:5060 at 20:44:25.625976: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK0jpBSQtmQay2p;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582865 INVITE Server: YATE/4.2.1 Contact: Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Type: application/sdp Content-Length: 309 v=0 o=yate 1521837859 1521837862 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:25.626115 [DEBUG] switch_core_media.c:6269 Remote address:port [207.2.108.132:6552] has not changed. 2018-03-23 20:44:25.626115 [DEBUG] sofia.c:6654 Passing 200 OK to other leg v=0 o=yate 1521837859 1521837862 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.626115 [DEBUG] switch_core_media.c:653 Audio params are unchanged for sofia/internal/18088432525@207.2.106.136. 2018-03-23 20:44:25.626115 [INFO] sofia.c:6704 Activating T38 Passthru 2018-03-23 20:44:25.626115 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [completing][200] 2018-03-23 20:44:25.626115 [DEBUG] sofia.c:7091 Duplicate SDP v=0 o=yate 1521837859 1521837862 IN IP4 207.2.108.132 s=SIP Call c=IN IP4 207.2.108.132 t=0 0 m=image 6552 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.626115 [DEBUG] mod_sofia.c:2132 Responding with 200 [OK] 2018-03-23 20:44:25.626115 [DEBUG] switch_core_media.c:9390 sofia/internal/safarov@207.2.108.90 image media sdp: v=0 o=FreeSWITCH 1521821095 1521821100 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 16764 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy send 371 bytes to udp/[207.2.106.136]:5060 at 20:44:25.627257: ------------------------------------------------------------------------ ACK sip:mod_sofia@207.2.106.136:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK248vvDvUHva8D Max-Forwards: 70 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582865 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:25.626115 [DEBUG] sofia.c:7084 Channel sofia/internal/18088432525@207.2.106.136 entering state [ready][200] recv 324 bytes from udp/[207.2.108.90]:5060 at 20:44:25.835984: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK1UF4tjBrmKmNj From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582867 INVITE User-Agent: Servpac VOIP v5 Content-Length: 0 ------------------------------------------------------------------------ recv 851 bytes from udp/[207.2.108.90]:5060 at 20:44:25.837334: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK1UF4tjBrmKmNj From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582867 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 Content-Type: application/sdp Content-Length: 310 v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ 2018-03-23 20:44:25.836239 [DEBUG] switch_core_media.c:6245 VIDEO RTP CHANGING DEST TO: [207.2.108.90:31882] 2018-03-23 20:44:25.836239 [DEBUG] switch_core_media.c:6269 Remote address:port [207.2.108.90:27888] has not changed. 2018-03-23 20:44:25.836239 [DEBUG] sofia.c:6654 Passing 200 OK to other leg v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.836239 [DEBUG] switch_core_media.c:653 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:25.836239 [INFO] sofia.c:6704 Activating T38 Passthru 2018-03-23 20:44:25.836239 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [completing][200] 2018-03-23 20:44:25.836239 [DEBUG] sofia.c:7091 Duplicate SDP v=0 o=Servpac 1521809968 1521809970 IN IP4 207.2.108.90 s=Servpac c=IN IP4 207.2.108.90 t=0 0 m=image 27888 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-03-23 20:44:25.836239 [DEBUG] mod_sofia.c:2132 Responding with 200 [OK] 2018-03-23 20:44:25.836239 [DEBUG] switch_core_media.c:9390 sofia/internal/18088432525@207.2.106.136 image media sdp: v=0 o=FreeSWITCH 1521818923 1521818928 IN IP4 217.12.247.212 s=FreeSWITCH c=IN IP4 217.12.247.212 t=0 0 m=image 18932 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy send 330 bytes to udp/[207.2.108.90]:5060 at 20:44:25.840107: ------------------------------------------------------------------------ ACK sip:mod_sofia@207.2.108.90:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK3D2Ny8cZe50tS Max-Forwards: 70 From: ;tag=NmXUXQaFDvQ1D To: "" ;tag=jp16tFyeQ9ZNm Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582867 ACK Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:25.846056 [DEBUG] sofia.c:7084 Channel sofia/internal/safarov@207.2.108.90 entering state [ready][200] 2018-03-23 20:44:25.846056 [DEBUG] switch_core_media.c:4041 sofia/internal/safarov@207.2.108.90 T38 ACCEPT on response 2018-03-23 20:44:25.846056 [DEBUG] switch_core_media.c:4146 sofia/internal/safarov@207.2.108.90 T38 IS POSSIBLE on response 2018-03-23 20:44:25.846056 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:25.846056 [DEBUG] switch_core_media.c:7257 Video params are unchanged for sofia/internal/safarov@207.2.108.90. 2018-03-23 20:44:25.906088 [DEBUG] switch_rtp.c:7308 Correct video ip/port confirmed. 2018-03-23 20:44:27.546066 [DEBUG] switch_rtp.c:6758 Correct video RTCP ip/port confirmed. 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 566 bytes from udp/[207.2.108.90]:5060 at 20:44:47.244925: ------------------------------------------------------------------------ BYE sip:18088432525@217.12.247.212:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 207.2.108.90;rport;branch=z9hG4bK7e2KD3K8Kca5D Max-Forwards: 70 From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582866 BYE User-Agent: Servpac VOIP v5 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:47.236058 [NOTICE] sofia.c:1012 Hangup sofia/internal/safarov@207.2.108.90 [CS_EXECUTE] [NORMAL_CLEARING] 2018-03-23 20:44:47.236058 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/safarov@207.2.108.90] 2018-03-23 20:44:47.236058 [DEBUG] switch_core_session.c:2577 sofia/internal/safarov@207.2.108.90 skip receive message [VIDEO_REFRESH_REQ] (channel is hungup already) 2018-03-23 20:44:47.246593 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/18088432525@207.2.106.136] 2018-03-23 20:44:47.246593 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/internal/18088432525@207.2.106.136 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/18088432525@207.2.106.136) State EXCHANGE_MEDIA going to sleep 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.106.136) Running State Change CS_HANGUP (Cur 2 Tot 566) 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/18088432525@207.2.106.136) Callstate Change ACTIVE -> HANGUP 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/18088432525@207.2.106.136) State HANGUP 2018-03-23 20:44:47.246593 [DEBUG] mod_sofia.c:432 sofia/internal/18088432525@207.2.106.136 Overriding SIP cause 480 with 200 from the other leg 2018-03-23 20:44:47.246593 [DEBUG] mod_sofia.c:438 Channel sofia/internal/18088432525@207.2.106.136 hanging up, cause: NORMAL_CLEARING 2018-03-23 20:44:47.246593 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/18088432525@207.2.106.136 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:60 sofia/internal/18088432525@207.2.106.136 Standard HANGUP, cause: NORMAL_CLEARING 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/18088432525@207.2.106.136) State HANGUP going to sleep 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/18088432525@207.2.106.136) State Change CS_HANGUP -> CS_REPORTING 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/18088432525@207.2.106.136) Running State Change CS_REPORTING (Cur 2 Tot 566) 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/18088432525@207.2.106.136) State REPORTING 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:174 sofia/internal/18088432525@207.2.106.136 Standard REPORTING, cause: NORMAL_CLEARING 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/18088432525@207.2.106.136) State REPORTING going to sleep 2018-03-23 20:44:47.246593 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/18088432525@207.2.106.136) State Change CS_REPORTING -> CS_DESTROY 2018-03-23 20:44:47.246593 [DEBUG] switch_core_session.c:1665 Session 566 (sofia/internal/18088432525@207.2.106.136) Locked, Waiting on external entities send 509 bytes to udp/[207.2.108.90]:5060 at 20:44:47.252178: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 207.2.108.90;rport=5060;branch=z9hG4bK7e2KD3K8Kca5D From: "" ;tag=jp16tFyeQ9ZNm To: ;tag=NmXUXQaFDvQ1D Call-ID: cb255edd-a97d-1236-f4b1-005056a1715a CSeq: 120582866 BYE 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-Length: 0 ------------------------------------------------------------------------ send 595 bytes to udp/[207.2.106.136]:5060 at 20:44:47.252284: ------------------------------------------------------------------------ BYE sip:mod_sofia@207.2.106.136:5060 SIP/2.0 Via: SIP/2.0/UDP 217.12.247.212;rport;branch=z9hG4bK4pUe03X2BeQDN Max-Forwards: 70 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582866 BYE 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 Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ 2018-03-23 20:44:47.256167 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/safarov@207.2.108.90 skip receive message [UNBRIDGE] (channel is hungup already) 2018-03-23 20:44:47.256167 [DEBUG] switch_core_session.c:2815 sofia/internal/safarov@207.2.108.90 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/safarov@207.2.108.90) State EXECUTE going to sleep 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_HANGUP (Cur 2 Tot 566) 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/safarov@207.2.108.90) Callstate Change ACTIVE -> HANGUP 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/safarov@207.2.108.90) State HANGUP 2018-03-23 20:44:47.256167 [DEBUG] mod_sofia.c:438 Channel sofia/internal/safarov@207.2.108.90 hanging up, cause: NORMAL_CLEARING 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:60 sofia/internal/safarov@207.2.108.90 Standard HANGUP, cause: NORMAL_CLEARING 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/safarov@207.2.108.90) State HANGUP going to sleep 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/safarov@207.2.108.90) State Change CS_HANGUP -> CS_REPORTING 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/safarov@207.2.108.90) Running State Change CS_REPORTING (Cur 2 Tot 566) 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/safarov@207.2.108.90) State REPORTING 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:174 sofia/internal/safarov@207.2.108.90 Standard REPORTING, cause: NORMAL_CLEARING 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/safarov@207.2.108.90) State REPORTING going to sleep 2018-03-23 20:44:47.256167 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/safarov@207.2.108.90) State Change CS_REPORTING -> CS_DESTROY 2018-03-23 20:44:47.256167 [DEBUG] switch_core_session.c:1665 Session 565 (sofia/internal/safarov@207.2.108.90) Locked, Waiting on external entities 2018-03-23 20:44:47.267520 [NOTICE] switch_core_session.c:1683 Session 566 (sofia/internal/18088432525@207.2.106.136) Ended 2018-03-23 20:44:47.267520 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/18088432525@207.2.106.136 [CS_DESTROY] 2018-03-23 20:44:47.267520 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/18088432525@207.2.106.136) Running State Change CS_DESTROY (Cur 1 Tot 566) 2018-03-23 20:44:47.267520 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/18088432525@207.2.106.136) State DESTROY 2018-03-23 20:44:47.267520 [DEBUG] mod_sofia.c:343 sofia/internal/18088432525@207.2.106.136 SOFIA DESTROY 2018-03-23 20:44:47.267520 [DEBUG] switch_core_state_machine.c:181 sofia/internal/18088432525@207.2.106.136 Standard DESTROY 2018-03-23 20:44:47.267520 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/18088432525@207.2.106.136) State DESTROY going to sleep 2018-03-23 20:44:47.276064 [DEBUG] switch_core_media.c:5970 sofia/internal/safarov@207.2.108.90 Video thread ended 2018-03-23 20:44:47.276064 [NOTICE] switch_core_session.c:1683 Session 565 (sofia/internal/safarov@207.2.108.90) Ended 2018-03-23 20:44:47.276064 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/safarov@207.2.108.90 [CS_DESTROY] 2018-03-23 20:44:47.276064 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/safarov@207.2.108.90) Running State Change CS_DESTROY (Cur 0 Tot 566) 2018-03-23 20:44:47.276064 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/safarov@207.2.108.90) State DESTROY 2018-03-23 20:44:47.276064 [DEBUG] mod_sofia.c:343 sofia/internal/safarov@207.2.108.90 SOFIA DESTROY 2018-03-23 20:44:47.276064 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[97] PLC[0] FEC[0] 2018-03-23 20:44:47.276064 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2018-03-23 20:44:47.276064 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2018-03-23 20:44:47.276064 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[139] Bytes encoded[12504] Encoded length ms[2780] Average encoded bitrate bps[50016] 2018-03-23 20:44:47.276064 [DEBUG] switch_core_state_machine.c:181 sofia/internal/safarov@207.2.108.90 Standard DESTROY 2018-03-23 20:44:47.276064 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/safarov@207.2.108.90) State DESTROY going to sleep recv 381 bytes from udp/[207.2.106.136]:5060 at 20:44:47.521497: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 217.12.247.212;rport=5060;branch=z9hG4bK4pUe03X2BeQDN;received=217.12.247.212 From: "" ;tag=pXpmZjUja5DmS To: ;tag=595902636 Call-ID: caf76fd9-a97d-1236-4888-000c296ca80b CSeq: 120582866 BYE Server: YATE/4.2.1 Allow: ACK, INVITE, BYE, CANCEL, REFER, OPTIONS, INFO Content-Length: 0 ------------------------------------------------------------------------ freeswitch@node2.docker.rcsnet.ru> /exit