freeswitch@pbx2.conexo.tech>
2021-02-19 08:15:22.932813 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
2021-02-19 08:15:22.932813 [NOTICE] switch_channel.c:1118 New Channel sofia/default/01119112588@212.97.34.174 [ccf81626-93f6-423c-933e-abb7b879d62d]
2021-02-19 08:15:22.932813 [DEBUG] mod_sofia.c:5089 (sofia/default/01119112588@212.97.34.174) State Change CS_NEW -> CS_INIT
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_INIT (Cur 1 Tot 58099)
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:628 (sofia/default/01119112588@212.97.34.174) State INIT
2021-02-19 08:15:22.932813 [DEBUG] mod_sofia.c:93 sofia/default/01119112588@212.97.34.174 SOFIA INIT
2021-02-19 08:15:22.932813 [DEBUG] sofia_glue.c:1618 sofia/default/01119112588@212.97.34.174 sending invite version: 1.10.5 -release.8 64bit
Local SDP:
v=0
o=FreeSWITCH 1613700074 1613700075 IN IP4 212.97.34.173
s=FreeSWITCH
c=IN IP4 212.97.34.173
t=0 0
m=audio 18848 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
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:40 sofia/default/01119112588@212.97.34.174 Standard INIT
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:48 (sofia/default/01119112588@212.97.34.174) State Change CS_INIT -> CS_ROUTING
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:628 (sofia/default/01119112588@212.97.34.174) State INIT going to sleep
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_ROUTING (Cur 1 Tot 58099)
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:644 (sofia/default/01119112588@212.97.34.174) State ROUTING
2021-02-19 08:15:22.932813 [DEBUG] mod_sofia.c:154 sofia/default/01119112588@212.97.34.174 SOFIA ROUTING
2021-02-19 08:15:22.932813 [DEBUG] switch_ivr_originate.c:67 (sofia/default/01119112588@212.97.34.174) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:644 (sofia/default/01119112588@212.97.34.174) State ROUTING going to sleep
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 58099)
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:663 (sofia/default/01119112588@212.97.34.174) State CONSUME_MEDIA
2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:663 (sofia/default/01119112588@212.97.34.174) State CONSUME_MEDIA going to sleep
2021-02-19 08:15:22.932813 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [calling][0]
2021-02-19 08:15:22.952805 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
2021-02-19 08:15:22.952805 [NOTICE] switch_channel.c:1118 New Channel sofia/default/01119112588@212.97.34.174 [212dc9f6-8efc-4fe6-8990-fa765ac332a2]
2021-02-19 08:15:22.952805 [DEBUG] mod_sofia.c:5089 (sofia/default/01119112588@212.97.34.174) State Change CS_NEW -> CS_INIT
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_INIT (Cur 2 Tot 58100)
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:628 (sofia/default/01119112588@212.97.34.174) State INIT
2021-02-19 08:15:22.952805 [DEBUG] mod_sofia.c:93 sofia/default/01119112588@212.97.34.174 SOFIA INIT
2021-02-19 08:15:22.952805 [DEBUG] sofia_glue.c:1618 sofia/default/01119112588@212.97.34.174 sending invite version: 1.10.5 -release.8 64bit
Local SDP:
v=0
o=FreeSWITCH 1613698778 1613698779 IN IP4 212.97.34.173
s=FreeSWITCH
c=IN IP4 212.97.34.173
t=0 0
m=audio 20144 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
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:40 sofia/default/01119112588@212.97.34.174 Standard INIT
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:48 (sofia/default/01119112588@212.97.34.174) State Change CS_INIT -> CS_ROUTING
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:628 (sofia/default/01119112588@212.97.34.174) State INIT going to sleep
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_ROUTING (Cur 2 Tot 58100)
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:644 (sofia/default/01119112588@212.97.34.174) State ROUTING
2021-02-19 08:15:22.952805 [DEBUG] mod_sofia.c:154 sofia/default/01119112588@212.97.34.174 SOFIA ROUTING
2021-02-19 08:15:22.952805 [DEBUG] switch_ivr_originate.c:67 (sofia/default/01119112588@212.97.34.174) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:644 (sofia/default/01119112588@212.97.34.174) State ROUTING going to sleep
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 58100)
2021-02-19 08:15:22.952805 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [calling][0]
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:663 (sofia/default/01119112588@212.97.34.174) State CONSUME_MEDIA
2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:663 (sofia/default/01119112588@212.97.34.174) State CONSUME_MEDIA going to sleep
2021-02-19 08:15:23.012808 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [proceeding][183]
2021-02-19 08:15:23.012808 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=FreeSWITCH 1613700675 1613700676 IN IP4 212.97.34.172
s=FreeSWITCH
c=IN IP4 212.97.34.172
t=0 0
m=audio 18248 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2021-02-19 08:15:23.012808 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/01119112588@212.97.34.174!
2021-02-19 08:15:23.012808 [DEBUG] switch_channel.c:3565 (sofia/default/01119112588@212.97.34.174) Callstate Change DOWN -> EARLY
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/01119112588@212.97.34.174 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2021-02-19 08:15:23.012808 [DEBUG] switch_core_codec.c:111 sofia/default/01119112588@212.97.34.174 Original read codec set to PCMA:8
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:5911 sofia/default/01119112588@212.97.34.174 Set 2833 dtmf send payload to 101 recv payload to 101
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/01119112588@212.97.34.174] 212.97.34.173 port 20144 -> 212.97.34.172 port 18248 codec: 8 ms: 20
2021-02-19 08:15:23.012808 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:8977 sofia/default/01119112588@212.97.34.174 Set 2833 dtmf send payload to 101
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:8984 sofia/default/01119112588@212.97.34.174 Set 2833 dtmf receive payload to 101
2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:9007 sofia/default/01119112588@212.97.34.174 Set rtp dtmf delay to 40
2021-02-19 08:15:23.032808 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [proceeding][183]
2021-02-19 08:15:23.032808 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=FreeSWITCH 1613687817 1613687818 IN IP4 212.97.34.172
s=FreeSWITCH
c=IN IP4 212.97.34.172
t=0 0
m=audio 31106 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2021-02-19 08:15:23.032808 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/01119112588@212.97.34.174!
2021-02-19 08:15:23.032808 [DEBUG] switch_channel.c:3565 (sofia/default/01119112588@212.97.34.174) Callstate Change DOWN -> EARLY
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/01119112588@212.97.34.174 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2021-02-19 08:15:23.032808 [DEBUG] switch_core_codec.c:111 sofia/default/01119112588@212.97.34.174 Original read codec set to PCMA:8
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:5911 sofia/default/01119112588@212.97.34.174 Set 2833 dtmf send payload to 101 recv payload to 101
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/01119112588@212.97.34.174] 212.97.34.173 port 18848 -> 212.97.34.172 port 31106 codec: 8 ms: 20
2021-02-19 08:15:23.032808 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:8977 sofia/default/01119112588@212.97.34.174 Set 2833 dtmf send payload to 101
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:8984 sofia/default/01119112588@212.97.34.174 Set 2833 dtmf receive payload to 101
2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:9007 sofia/default/01119112588@212.97.34.174 Set rtp dtmf delay to 40
2021-02-19 08:15:25.992824 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completing][200]
2021-02-19 08:15:25.992824 [DEBUG] sofia.c:7333 Duplicate SDP
v=0
o=FreeSWITCH 1613700675 1613700676 IN IP4 212.97.34.172
s=FreeSWITCH
c=IN IP4 212.97.34.172
t=0 0
m=audio 18248 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2021-02-19 08:15:25.992824 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
2021-02-19 08:15:25.992824 [NOTICE] sofia.c:8445 Channel [sofia/default/01119112588@212.97.34.174] has been answered
2021-02-19 08:15:25.992824 [DEBUG] switch_channel.c:3865 (sofia/default/01119112588@212.97.34.174) Callstate Change EARLY -> ACTIVE
2021-02-19 08:15:26.012819 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/01119112588@212.97.34.174]
2021-02-19 08:15:26.012819 [INFO] switch_channel.c:3213 sofia/default/01119112588@212.97.34.174 Flipping CID from "" <01119112588> to "Outbound Call" <01119112588>
2021-02-19 08:15:26.012819 [DEBUG] mod_commands.c:5094 (sofia/default/01119112588@212.97.34.174) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
2021-02-19 08:15:26.012819 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_EXECUTE (Cur 2 Tot 58100)
2021-02-19 08:15:26.012819 [DEBUG] switch_core_state_machine.c:651 (sofia/default/01119112588@212.97.34.174) State EXECUTE
2021-02-19 08:15:26.012819 [DEBUG] mod_sofia.c:209 sofia/default/01119112588@212.97.34.174 SOFIA EXECUTE
2021-02-19 08:15:26.012819 [DEBUG] switch_core_state_machine.c:329 sofia/default/01119112588@212.97.34.174 Standard EXECUTE
EXECUTE [depth=0] sofia/default/01119112588@212.97.34.174 txfax(/opt/ASTPP/web_interface/astpp/FAX/send_fax_files/20210219071442.tiff)
2021-02-19 08:15:26.012819 [DEBUG] mod_spandsp_fax.c:1514 Raw read codec activation Success L16 20000
2021-02-19 08:15:26.012819 [DEBUG] switch_core_codec.c:223 sofia/default/01119112588@212.97.34.174 Push codec L16:100
2021-02-19 08:15:26.012819 [DEBUG] mod_spandsp_fax.c:1532 Raw write codec activation Success L16
2021-02-19 08:15:26.052828 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
2021-02-19 08:15:26.112823 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completing][200]
2021-02-19 08:15:26.112823 [DEBUG] sofia.c:7333 Duplicate SDP
v=0
o=FreeSWITCH 1613687817 1613687818 IN IP4 212.97.34.172
s=FreeSWITCH
c=IN IP4 212.97.34.172
t=0 0
m=audio 31106 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2021-02-19 08:15:26.112823 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
2021-02-19 08:15:26.112823 [NOTICE] sofia.c:8445 Channel [sofia/default/01119112588@212.97.34.174] has been answered
2021-02-19 08:15:26.112823 [DEBUG] switch_channel.c:3865 (sofia/default/01119112588@212.97.34.174) Callstate Change EARLY -> ACTIVE
2021-02-19 08:15:26.132827 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/01119112588@212.97.34.174]
2021-02-19 08:15:26.132827 [INFO] switch_channel.c:3213 sofia/default/01119112588@212.97.34.174 Flipping CID from "" <01119112588> to "Outbound Call" <01119112588>
2021-02-19 08:15:26.132827 [DEBUG] mod_commands.c:5094 (sofia/default/01119112588@212.97.34.174) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
2021-02-19 08:15:26.132827 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_EXECUTE (Cur 2 Tot 58100)
2021-02-19 08:15:26.132827 [DEBUG] switch_core_state_machine.c:651 (sofia/default/01119112588@212.97.34.174) State EXECUTE
2021-02-19 08:15:26.132827 [DEBUG] mod_sofia.c:209 sofia/default/01119112588@212.97.34.174 SOFIA EXECUTE
2021-02-19 08:15:26.132827 [DEBUG] switch_core_state_machine.c:329 sofia/default/01119112588@212.97.34.174 Standard EXECUTE
EXECUTE [depth=0] sofia/default/01119112588@212.97.34.174 txfax(/opt/ASTPP/web_interface/astpp/FAX/send_fax_files/20210219071442.tiff)
2021-02-19 08:15:26.132827 [DEBUG] mod_spandsp_fax.c:1514 Raw read codec activation Success L16 20000
2021-02-19 08:15:26.132827 [DEBUG] switch_core_codec.c:223 sofia/default/01119112588@212.97.34.174 Push codec L16:100
2021-02-19 08:15:26.132827 [DEBUG] mod_spandsp_fax.c:1532 Raw write codec activation Success L16
2021-02-19 08:15:26.172812 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
2021-02-19 08:15:26.852829 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [received][100]
2021-02-19 08:15:26.852829 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=FreeSWITCH 1613687817 1613687820 IN IP4 212.97.34.172
s=FreeSWITCH
c=IN IP4 212.97.34.172
t=0 0
m=image 31106 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:500
a=T38FaxMaxDatagram:500
a=T38FaxUdpEC:t38UDPRedundancy
2021-02-19 08:15:26.852829 [DEBUG] switch_core_media.c:5185 sofia/default/01119112588@212.97.34.174 T38 ACCEPT on request
2021-02-19 08:15:26.852829 [DEBUG] switch_core_media.c:5289 sofia/default/01119112588@212.97.34.174 T38 IS POSSIBLE on request
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1095 T38 SDP Origin = FreeSWITCH
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1096 T38FaxVersion = 0
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1097 T38MaxBitRate = 14400
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1098 T38FaxFillBitRemoval = 0
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1099 T38FaxTranscodingMMR = 0
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1100 T38FaxTranscodingJBIG = 0
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1101 T38FaxRateManagement = 'transferredTCF'
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1102 T38FaxMaxBuffer = 500
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1103 T38FaxMaxDatagram = 500
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1104 T38FaxUdpEC = 't38UDPRedundancy'
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1105 T38VendorInfo = ''
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1106 ip = '212.97.34.172'
2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1108 port = 31106
2021-02-19 08:15:26.872822 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:31106] has not changed.
2021-02-19 08:15:26.872822 [DEBUG] switch_core_media.c:11914 sofia/default/01119112588@212.97.34.174 image media sdp:
v=0
o=FreeSWITCH 1613700074 1613700076 IN IP4 212.97.34.173
s=FreeSWITCH
c=IN IP4 212.97.34.173
t=0 0
m=image 18848 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:500
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy
2021-02-19 08:15:26.872822 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:31106] has not changed.
2021-02-19 08:15:26.872822 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completed][200]
2021-02-19 08:15:26.892817 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
2021-02-19 08:15:28.512808 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [received][100]
2021-02-19 08:15:28.512808 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=FreeSWITCH 1613700675 1613700678 IN IP4 212.97.34.172
s=FreeSWITCH
c=IN IP4 212.97.34.172
t=0 0
m=image 18248 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:500
a=T38FaxMaxDatagram:500
a=T38FaxUdpEC:t38UDPRedundancy
2021-02-19 08:15:28.512808 [DEBUG] switch_core_media.c:5185 sofia/default/01119112588@212.97.34.174 T38 ACCEPT on request
2021-02-19 08:15:28.512808 [DEBUG] switch_core_media.c:5289 sofia/default/01119112588@212.97.34.174 T38 IS POSSIBLE on request
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1095 T38 SDP Origin = FreeSWITCH
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1096 T38FaxVersion = 0
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1097 T38MaxBitRate = 14400
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1098 T38FaxFillBitRemoval = 0
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1099 T38FaxTranscodingMMR = 0
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1100 T38FaxTranscodingJBIG = 0
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1101 T38FaxRateManagement = 'transferredTCF'
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1102 T38FaxMaxBuffer = 500
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1103 T38FaxMaxDatagram = 500
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1104 T38FaxUdpEC = 't38UDPRedundancy'
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1105 T38VendorInfo = ''
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1106 ip = '212.97.34.172'
2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1108 port = 18248
2021-02-19 08:15:28.532811 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:18248] has not changed.
2021-02-19 08:15:28.532811 [DEBUG] switch_core_media.c:11914 sofia/default/01119112588@212.97.34.174 image media sdp:
v=0
o=FreeSWITCH 1613698778 1613698780 IN IP4 212.97.34.173
s=FreeSWITCH
c=IN IP4 212.97.34.173
t=0 0
m=image 20144 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:500
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy
2021-02-19 08:15:28.532811 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:18248] has not changed.
2021-02-19 08:15:28.532811 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completed][200]
2021-02-19 08:15:28.552810 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:346 === Negotiation Result =======================================================
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:347 Remote station id: 01119112588
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:348 Local station id: 509
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:349 Transfer Rate: 14400
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:350 ECM status off
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:351 T38 status negotiated
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:352 remote country:
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:353 remote vendor:
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:354 remote model:
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:356 Total fax pages: 0
2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:359 ==============================================================================
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:346 === Negotiation Result =======================================================
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:347 Remote station id: 01119112588
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:348 Local station id: 509
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:349 Transfer Rate: 14400
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:350 ECM status off
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:351 T38 status negotiated
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:352 remote country:
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:353 remote vendor:
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:354 remote model:
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:356 Total fax pages: 0
2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:359 ==============================================================================
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:471 ==== Page Sent ===============================================================
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:472 Page no = 1
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:474 Total fax pages: 1
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:476 Image type = bi-level (bi-level in the file)
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:477 Image size = 1728 x 1094 pixels (1728 x 1078 pixels in the file)
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:478 Image resolution = 8040/m x 3850/m (8031/m x 3858/m in the file)
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:479 Compression = T.4 2-D (4)
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:480 Compressed image size = 3376 bytes
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:481 Bad rows = 0
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:482 Longest bad row run = 0
2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:483 ==============================================================================
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:471 ==== Page Sent ===============================================================
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:472 Page no = 1
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:474 Total fax pages: 1
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:476 Image type = bi-level (bi-level in the file)
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:477 Image size = 1728 x 1094 pixels (1728 x 1078 pixels in the file)
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:478 Image resolution = 8040/m x 3850/m (8031/m x 3858/m in the file)
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:479 Compression = T.4 2-D (4)
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:480 Compressed image size = 3376 bytes
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:481 Bad rows = 0
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:482 Longest bad row run = 0
2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:483 ==============================================================================
2021-02-19 08:15:49.192807 [NOTICE] sofia.c:1089 Hangup sofia/default/01119112588@212.97.34.174 [CS_EXECUTE] [NORMAL_CLEARING]
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:545 ==============================================================================
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:549 Fax successfully sent.
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:562 Remote station id: 01119112588
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:563 Local station id: 509
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:564 Pages transferred: 1
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:566 Total fax pages: 1
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:567 Image resolution: 8040x3850
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:568 Transfer Rate: 14400
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:570 ECM status off
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:571 T38 status negotiated
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:572 remote country:
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:573 remote vendor:
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:574 remote model:
2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:576 ==============================================================================
2021-02-19 08:15:49.192807 [DEBUG] switch_core_codec.c:248 sofia/default/01119112588@212.97.34.174 Restore previous codec PCMA:8.
2021-02-19 08:15:49.192807 [DEBUG] switch_core_session.c:2905 sofia/default/01119112588@212.97.34.174 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:651 (sofia/default/01119112588@212.97.34.174) State EXECUTE going to sleep
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_HANGUP (Cur 2 Tot 58100)
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:848 (sofia/default/01119112588@212.97.34.174) Callstate Change ACTIVE -> HANGUP
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:850 (sofia/default/01119112588@212.97.34.174) State HANGUP
2021-02-19 08:15:49.192807 [DEBUG] mod_sofia.c:453 Channel sofia/default/01119112588@212.97.34.174 hanging up, cause: NORMAL_CLEARING
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:60 sofia/default/01119112588@212.97.34.174 Standard HANGUP, cause: NORMAL_CLEARING
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:850 (sofia/default/01119112588@212.97.34.174) State HANGUP going to sleep
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:620 (sofia/default/01119112588@212.97.34.174) State Change CS_HANGUP -> CS_REPORTING
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_REPORTING (Cur 2 Tot 58100)
2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:936 (sofia/default/01119112588@212.97.34.174) State REPORTING
2021-02-19 08:15:49.192807 [INFO] mod_json_cdr.c:271 Process [ccf81626-93f6-423c-933e-abb7b879d62d.cdr.json]
2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:174 sofia/default/01119112588@212.97.34.174 Standard REPORTING, cause: NORMAL_CLEARING
2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:936 (sofia/default/01119112588@212.97.34.174) State REPORTING going to sleep
2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:611 (sofia/default/01119112588@212.97.34.174) State Change CS_REPORTING -> CS_DESTROY
2021-02-19 08:15:49.212808 [DEBUG] switch_core_session.c:1726 Session 58099 (sofia/default/01119112588@212.97.34.174) Locked, Waiting on external entities
2021-02-19 08:15:49.212808 [NOTICE] switch_core_session.c:1744 Session 58099 (sofia/default/01119112588@212.97.34.174) Ended
2021-02-19 08:15:49.212808 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/01119112588@212.97.34.174 [CS_DESTROY]
2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:739 (sofia/default/01119112588@212.97.34.174) Running State Change CS_DESTROY (Cur 1 Tot 58100)
2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:749 (sofia/default/01119112588@212.97.34.174) State DESTROY
2021-02-19 08:15:49.212808 [DEBUG] mod_sofia.c:364 sofia/default/01119112588@212.97.34.174 SOFIA DESTROY
2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:181 sofia/default/01119112588@212.97.34.174 Standard DESTROY
2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:749 (sofia/default/01119112588@212.97.34.174) State DESTROY going to sleep
2021-02-19 08:15:50.872826 [NOTICE] sofia.c:1089 Hangup sofia/default/01119112588@212.97.34.174 [CS_EXECUTE] [NORMAL_CLEARING]
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:545 ==============================================================================
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:549 Fax successfully sent.
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:562 Remote station id: 01119112588
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:563 Local station id: 509
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:564 Pages transferred: 1
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:566 Total fax pages: 1
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:567 Image resolution: 8040x3850
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:568 Transfer Rate: 14400
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:570 ECM status off
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:571 T38 status negotiated
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:572 remote country:
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:573 remote vendor:
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:574 remote model:
2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:576 ==============================================================================
2021-02-19 08:15:50.872826 [DEBUG] switch_core_codec.c:248 sofia/default/01119112588@212.97.34.174 Restore previous codec PCMA:8.
2021-02-19 08:15:50.872826 [DEBUG] switch_core_session.c:2905 sofia/default/01119112588@212.97.34.174 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:651 (sofia/default/01119112588@212.97.34.174) State EXECUTE going to sleep
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_HANGUP (Cur 1 Tot 58100)
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:848 (sofia/default/01119112588@212.97.34.174) Callstate Change ACTIVE -> HANGUP
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:850 (sofia/default/01119112588@212.97.34.174) State HANGUP
2021-02-19 08:15:50.872826 [DEBUG] mod_sofia.c:453 Channel sofia/default/01119112588@212.97.34.174 hanging up, cause: NORMAL_CLEARING
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:60 sofia/default/01119112588@212.97.34.174 Standard HANGUP, cause: NORMAL_CLEARING
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:850 (sofia/default/01119112588@212.97.34.174) State HANGUP going to sleep
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:620 (sofia/default/01119112588@212.97.34.174) State Change CS_HANGUP -> CS_REPORTING
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:585 (sofia/default/01119112588@212.97.34.174) Running State Change CS_REPORTING (Cur 1 Tot 58100)
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:936 (sofia/default/01119112588@212.97.34.174) State REPORTING
2021-02-19 08:15:50.872826 [INFO] mod_json_cdr.c:271 Process [212dc9f6-8efc-4fe6-8990-fa765ac332a2.cdr.json]
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:174 sofia/default/01119112588@212.97.34.174 Standard REPORTING, cause: NORMAL_CLEARING
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:936 (sofia/default/01119112588@212.97.34.174) State REPORTING going to sleep
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:611 (sofia/default/01119112588@212.97.34.174) State Change CS_REPORTING -> CS_DESTROY
2021-02-19 08:15:50.872826 [DEBUG] switch_core_session.c:1726 Session 58100 (sofia/default/01119112588@212.97.34.174) Locked, Waiting on external entities
2021-02-19 08:15:50.872826 [NOTICE] switch_core_session.c:1744 Session 58100 (sofia/default/01119112588@212.97.34.174) Ended
2021-02-19 08:15:50.872826 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/01119112588@212.97.34.174 [CS_DESTROY]
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:739 (sofia/default/01119112588@212.97.34.174) Running State Change CS_DESTROY (Cur 0 Tot 58100)
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:749 (sofia/default/01119112588@212.97.34.174) State DESTROY
2021-02-19 08:15:50.872826 [DEBUG] mod_sofia.c:364 sofia/default/01119112588@212.97.34.174 SOFIA DESTROY
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:181 sofia/default/01119112588@212.97.34.174 Standard DESTROY
2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:749 (sofia/default/01119112588@212.97.34.174) State DESTROY going to sleep