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: "" <sip:safarov@207.2.108.90>;tag=5vva6tFU6Fe8F
To: <sip:8432525@217.12.247.212>
Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a
CSeq: 120641941 INVITE
Contact: <sip:mod_sofia@207.2.108.90:5060>
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: <sip:safarov@207.2.108.90>;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: "" <sip:safarov@207.2.108.90>;tag=5vva6tFU6Fe8F
To: <sip:8432525@217.12.247.212>
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 <safarov>->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: "" <sip:safarov@servpac.com>;tag=r98pcjKBrQcjc
To: <sip:18088432525@207.2.108.136>
Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b
CSeq: 120641940 INVITE
Contact: <sip:mod_sofia@217.12.247.212:5060>
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: <sip:safarov@servpac.com>;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: "" <sip:safarov@servpac.com>;tag=r98pcjKBrQcjc
To: <sip:18088432525@207.2.108.136>
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: "" <sip:safarov@servpac.com>;tag=r98pcjKBrQcjc
To: <sip:18088432525@207.2.108.136>;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: <sip:18088432525@207.2.108.136:5060>
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: "" <sip:safarov@207.2.108.90>;tag=5vva6tFU6Fe8F
To: <sip:8432525@217.12.247.212>;tag=Q0FyaQ27tepZg
Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a
CSeq: 120641941 INVITE
Contact: <sip:8432525@217.12.247.212:5060;transport=udp>
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" <sip:18088432525@217.12.247.212>;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: "" <sip:safarov@servpac.com>;tag=r98pcjKBrQcjc
To: <sip:18088432525@207.2.108.136>;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: <sip:18088432525@207.2.108.136:5060>
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: "" <sip:safarov@servpac.com>;tag=r98pcjKBrQcjc
To: <sip:18088432525@207.2.108.136>;tag=1647169070
Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b
CSeq: 120641940 ACK
Contact: <sip:mod_sofia@217.12.247.212:5060>
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: "" <sip:safarov@207.2.108.90>;tag=5vva6tFU6Fe8F
To: <sip:8432525@217.12.247.212>;tag=Q0FyaQ27tepZg
Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a
CSeq: 120641941 INVITE
Contact: <sip:8432525@217.12.247.212:5060;transport=udp>
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" <sip:18088432525@217.12.247.212>;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: "" <sip:safarov@207.2.108.90>;tag=5vva6tFU6Fe8F
To: <sip:8432525@217.12.247.212>;tag=Q0FyaQ27tepZg
Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a
CSeq: 120641941 ACK
Contact: <sip:mod_sofia@207.2.108.90:5060>
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: <sip:8432525@217.12.247.212>;tag=Q0FyaQ27tepZg
To: "" <sip:safarov@207.2.108.90>;tag=5vva6tFU6Fe8F
Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a
CSeq: 120641943 INFO
Contact: <sip:8432525@217.12.247.212:5060;transport=udp>
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: <sip:8432525@217.12.247.212>;tag=Q0FyaQ27tepZg
To: "" <sip:safarov@207.2.108.90>;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: <sip:18088432525@207.2.108.136>;tag=1647169070
To: <sip:safarov@servpac.com>;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: <sip:mod_sofia@207.2.108.136:5060>
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: <sip:18088432525@207.2.108.136>;tag=1647169070
To: <sip:safarov@servpac.com>;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: <sip:18088432525@207.2.108.136>;tag=1647169070
To: <sip:safarov@servpac.com>;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: <sip:18088432525@207.2.108.136>;tag=1647169070
To: <sip:safarov@servpac.com>;tag=r98pcjKBrQcjc
Call-ID: e42e8b29-aa90-1236-4888-000c296ca80b
CSeq: 268496 ACK
Max-Forwards: 70
Contact: <sip:mod_sofia@207.2.108.136:5060>
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: <sip:18088432525@207.2.108.136>;tag=1647169070
To: <sip:safarov@servpac.com>;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: <sip:mod_sofia@207.2.108.136:5060>
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: <sip:18088432525@207.2.108.136>;tag=1647169070
To: <sip:safarov@servpac.com>;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: <sip:8432525@217.12.247.212>;tag=Q0FyaQ27tepZg
To: "" <sip:safarov@207.2.108.90>;tag=5vva6tFU6Fe8F
Call-ID: e4540c92-aa90-1236-f4b1-005056a1715a
CSeq: 120641944 INVITE
Contact: <sip:8432525@217.12.247.212:5060;transport=udp>
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
------------------------------------------------------------------------