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