2019-09-24 17:53:41.782762 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [received][100]
2019-09-24 17:53:41.782762 [DEBUG] sofia.c:7058 Remote SDP:
v=0
o=- 2576636 2576637 IN IP4 192.168.16.212
s=-
c=IN IP4 192.168.16.212
t=0 0
m=audio 24088 RTP/AVP 18 8 0 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendonly
a=ptime:20

2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/204@freeswitch.test.xxx PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2019-09-24 17:53:41.782762 [DEBUG] switch_core_codec.c:111 sofia/internal/204@freeswitch.test.xxx Original read codec set to PCMA:8
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4747 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101 recv payload to 101
2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/204@freeswitch.test.xxx] X.Y.Z.102 port 31062 -> 192.168.16.212 port 24088 codec: 8 ms: 20
2019-09-24 17:53:41.782762 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
2019-09-24 17:53:41.815849 [DEBUG] switch_core_media.c:7109 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101
2019-09-24 17:53:41.815849 [DEBUG] switch_core_media.c:7116 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf receive payload to 101
2019-09-24 17:53:41.815849 [DEBUG] switch_core_media.c:7139 sofia/internal/204@freeswitch.test.xxx Set rtp dtmf delay to 40
2019-09-24 17:53:42.054862 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [completed][200]
2019-09-24 17:53:42.054862 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [ready][200]
2019-09-24 17:53:42.314861 [DEBUG] sofia.c:7048 Channel sofia/internal/202@192.168.16.187:65532 entering state [calling][0]
2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7048 Channel sofia/internal/202@192.168.16.187:65532 entering state [completing][200]
2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7058 Remote SDP:
v=0
o=202 1988239396 1988239397 IN IP4 192.168.16.187
s=-
c=IN IP4 192.168.16.187
t=0 0
m=audio 50028 RTP/AVP 18 8 0 2 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=maxptime:80
a=sendrecv
a=rtcp:50029

2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/202@192.168.16.187:65532 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2019-09-24 17:53:42.414911 [DEBUG] switch_core_codec.c:111 sofia/internal/202@192.168.16.187:65532 Original read codec set to PCMA:8
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4747 sofia/internal/202@192.168.16.187:65532 Set 2833 dtmf send payload to 101 recv payload to 101
2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7340 RESTABLISH MEDIA SDP:
v=0
o=FreeSWITCH 1569308594 1569308595 IN IP4 X.Y.Z.102
s=FreeSWITCH
c=IN IP4 X.Y.Z.102
t=0 0
m=audio 31828 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
a=rtcp:31829 IN IP4 X.Y.Z.102

2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/202@192.168.16.187:65532] X.Y.Z.102 port 31828 -> 192.168.16.187 port 50028 codec: 8 ms: 20
2019-09-24 17:53:42.414911 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 50029
2019-09-24 17:53:42.414911 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 50029
2019-09-24 17:53:42.414911 [DEBUG] switch_rtp.c:2559 Setting RTCP remote addr to 192.168.16.187:50029 2
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7109 sofia/internal/202@192.168.16.187:65532 Set 2833 dtmf send payload to 101
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7116 sofia/internal/202@192.168.16.187:65532 Set 2833 dtmf receive payload to 101
2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7139 sofia/internal/202@192.168.16.187:65532 Set rtp dtmf delay to 40
2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7048 Channel sofia/internal/202@192.168.16.187:65532 entering state [ready][200]
2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [calling][0]
2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [completing][200]
2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7058 Remote SDP:
v=0
o=- 2576636 2576637 IN IP4 192.168.16.212
s=-
c=IN IP4 192.168.16.212
t=0 0
m=audio 24088 RTP/AVP 18 8 0 101
a=rtpmap:18 G729/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendonly

2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4747 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101 recv payload to 101
2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7340 RESTABLISH MEDIA SDP:
v=0
o=FreeSWITCH 1569309359 1569309361 IN IP4 X.Y.Z.102
s=FreeSWITCH
c=IN IP4 X.Y.Z.102
t=0 0
m=audio 31062 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=recvonly

2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/204@freeswitch.test.xxx.
2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [ready][200]
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:1990 (sofia/internal/204@freeswitch.test.xxx) State Change CS_HIBERNATE -> CS_CONSUME_MEDIA
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/204@freeswitch.test.xxx) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 100)
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:1992 (sofia/internal/202@192.168.16.187:65532) State Change CS_HIBERNATE -> CS_CONSUME_MEDIA
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 100)
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/204@freeswitch.test.xxx) State CONSUME_MEDIA
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:944 (sofia/internal/204@freeswitch.test.xxx) State Change CS_CONSUME_MEDIA -> CS_RESET
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:944 (sofia/internal/202@192.168.16.187:65532) State Change CS_CONSUME_MEDIA -> CS_RESET
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA going to sleep
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/204@freeswitch.test.xxx) State CONSUME_MEDIA going to sleep
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_RESET (Cur 2 Tot 100)
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/204@freeswitch.test.xxx) Running State Change CS_RESET (Cur 2 Tot 100)
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/202@192.168.16.187:65532) State RESET
2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:161 sofia/internal/202@192.168.16.187:65532 SOFIA RESET
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:929 sofia/internal/202@192.168.16.187:65532 CUSTOM RESET
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/204@freeswitch.test.xxx) State RESET
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:188 sofia/internal/202@192.168.16.187:65532 Standard RESET
2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:161 sofia/internal/204@freeswitch.test.xxx SOFIA RESET
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:929 sofia/internal/204@freeswitch.test.xxx CUSTOM RESET
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:936 (sofia/internal/204@freeswitch.test.xxx) State Change CS_RESET -> CS_SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/202@192.168.16.187:65532) State RESET going to sleep
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/204@freeswitch.test.xxx) State RESET going to sleep
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/204@freeswitch.test.xxx) Running State Change CS_SOFT_EXECUTE (Cur 2 Tot 100)
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/204@freeswitch.test.xxx) State SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:954 sofia/internal/204@freeswitch.test.xxx CUSTOM SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:986 (sofia/internal/202@192.168.16.187:65532) State Change CS_RESET -> CS_SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_SOFT_EXECUTE (Cur 2 Tot 100)
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/202@192.168.16.187:65532) State SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:954 sofia/internal/202@192.168.16.187:65532 CUSTOM SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:400 sofia/internal/202@192.168.16.187:65532 Standard SOFT_EXECUTE
2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/202@192.168.16.187:65532) State SOFT_EXECUTE going to sleep
2019-09-24 17:53:42.454864 [DEBUG] switch_ivr_bridge.c:1469 (sofia/internal/202@192.168.16.187:65532) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 100)
2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA
2019-09-24 17:53:42.454864 [DEBUG] switch_ivr_bridge.c:893 sofia/internal/202@192.168.16.187:65532 CUSTOM HOLD
2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA going to sleep
2019-09-24 17:53:42.454864 [DEBUG] switch_ivr_bridge.c:1574 (sofia/internal/202@192.168.16.187:65532) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 100)
2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/202@192.168.16.187:65532) State EXCHANGE_MEDIA
2019-09-24 17:53:42.454864 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
2019-09-24 17:53:42.454864 [DEBUG] switch_channel.c:1844 (sofia/internal/204@freeswitch.test.xxx) Callstate Change ACTIVE -> HELD
2019-09-24 17:53:42.454864 [DEBUG] mod_sofia.c:1938 Not sending same id again "204" <204>
2019-09-24 17:53:42.474866 [DEBUG] mod_sofia.c:1938 Not sending same id again "202" <202>
2019-09-24 17:53:42.514859 [DEBUG] switch_rtp.c:1884 rtcp_stats_init: audio ssrc[1988418925] base_seq[31562]
2019-09-24 17:53:42.834867 [DEBUG] switch_ivr.c:623 sofia/internal/202@192.168.16.187:65532 Command Execute playback(local_stream://moh)
EXECUTE sofia/internal/202@192.168.16.187:65532 playback(local_stream://moh)
2019-09-24 17:53:42.834867 [DEBUG] mod_local_stream.c:866 Opening Stream [moh/8000] 8000hz
2019-09-24 17:53:42.834867 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
2019-09-24 17:53:53.894869 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [received][100]
2019-09-24 17:53:53.894869 [DEBUG] sofia.c:7058 Remote SDP:
v=0
o=- 2576636 2576639 IN IP4 192.168.16.212
s=-
c=IN IP4 192.168.16.212
t=0 0
m=audio 24088 RTP/AVP 18 8 0 101
a=rtpmap:18 G729/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv

2019-09-24 17:53:54.154870 [DEBUG] switch_ivr_play_say.c:1942 done playing file local_stream://moh
2019-09-24 17:53:54.154870 [DEBUG] switch_channel.c:2029 (sofia/internal/204@freeswitch.test.xxx) Callstate Change HELD -> UNHELD
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4747 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101 recv payload to 101
2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/204@freeswitch.test.xxx.
2019-09-24 17:53:54.154870 [DEBUG] sofia.c:7948 Processing updated SDP
2019-09-24 17:53:54.154870 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [completed][200]
2019-09-24 17:53:54.154870 [DEBUG] switch_core_io.c:941 (sofia/internal/204@freeswitch.test.xxx) Callstate Change UNHELD -> ACTIVE
2019-09-24 17:53:54.254861 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [ready][200]
2019-09-24 17:53:54.474860 [INFO] switch_rtp.c:7188 Auto Changing audio port from 192.168.16.212:24088 to X.Y.Z.98:29511