ramon@1yh7kc2:~/Desktop$ cat freeswitch.log.26 | grep 6b529c20-e5ae-4da8-a72f-cd11513c426e 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [NOTICE] switch_channel.c:1104 New Channel sofia/external-compact/7734136118@199.182.208.17:5060 [6b529c20-e5ae-4da8-a72f-cd11513c426e] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] mod_sofia.c:4808 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_NEW -> CS_INIT 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_INIT (Cur 835 Tot 2416172) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:627 (sofia/external-compact/7734136118@199.182.208.17:5060) State INIT 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] mod_sofia.c:90 sofia/external-compact/7734136118@199.182.208.17:5060 SOFIA INIT 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] sofia_glue.c:1254 sip:64.16.240.36:5060;transport=UDP Setting proxy route to sofia/external-compact/7734136118@199.182.208.17:5060 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] sofia_glue.c:1283 sofia/external-compact/7734136118@199.182.208.17:5060 sending invite version: 1.6.14 git e460bf8 2017-01-11 05:02:57Z 64bit 6b529c20-e5ae-4da8-a72f-cd11513c426e Local SDP: 6b529c20-e5ae-4da8-a72f-cd11513c426e v=0 6b529c20-e5ae-4da8-a72f-cd11513c426e o=FreeSWITCH 1485791715 1485791716 IN IP4 64.16.240.36 6b529c20-e5ae-4da8-a72f-cd11513c426e s=FreeSWITCH 6b529c20-e5ae-4da8-a72f-cd11513c426e c=IN IP4 64.16.240.36 6b529c20-e5ae-4da8-a72f-cd11513c426e t=0 0 6b529c20-e5ae-4da8-a72f-cd11513c426e m=audio 17788 RTP/AVP 9 0 101 6b529c20-e5ae-4da8-a72f-cd11513c426e a=rtpmap:9 G722/8000 6b529c20-e5ae-4da8-a72f-cd11513c426e a=rtpmap:0 PCMU/8000 6b529c20-e5ae-4da8-a72f-cd11513c426e a=rtpmap:101 telephone-event/8000 6b529c20-e5ae-4da8-a72f-cd11513c426e a=fmtp:101 0-16 6b529c20-e5ae-4da8-a72f-cd11513c426e a=rtcp-mux 6b529c20-e5ae-4da8-a72f-cd11513c426e a=rtcp:17788 IN IP4 64.16.240.36 6b529c20-e5ae-4da8-a72f-cd11513c426e a=ptime:20 6b529c20-e5ae-4da8-a72f-cd11513c426e a=sendrecv 6b529c20-e5ae-4da8-a72f-cd11513c426e 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:40 sofia/external-compact/7734136118@199.182.208.17:5060 Standard INIT 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:48 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_INIT -> CS_ROUTING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:627 (sofia/external-compact/7734136118@199.182.208.17:5060) State INIT going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_ROUTING (Cur 835 Tot 2416172) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:643 (sofia/external-compact/7734136118@199.182.208.17:5060) State ROUTING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] mod_sofia.c:143 sofia/external-compact/7734136118@199.182.208.17:5060 SOFIA ROUTING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_ivr_originate.c:67 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:643 (sofia/external-compact/7734136118@199.182.208.17:5060) State ROUTING going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_CONSUME_MEDIA (Cur 835 Tot 2416172) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] sofia.c:7041 Channel sofia/external-compact/7734136118@199.182.208.17:5060 entering state [calling][0] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:662 (sofia/external-compact/7734136118@199.182.208.17:5060) State CONSUME_MEDIA 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.543421 [DEBUG] switch_core_state_machine.c:662 (sofia/external-compact/7734136118@199.182.208.17:5060) State CONSUME_MEDIA going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.883420 [DEBUG] sofia.c:7041 Channel sofia/external-compact/7734136118@199.182.208.17:5060 entering state [proceeding][180] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.883420 [NOTICE] sofia.c:7149 Ring-Ready sofia/external-compact/7734136118@199.182.208.17:5060! 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.883420 [DEBUG] switch_channel.c:3345 (sofia/external-compact/7734136118@199.182.208.17:5060) Callstate Change DOWN -> RINGING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] sofia.c:7041 Channel sofia/external-compact/7734136118@199.182.208.17:5060 entering state [completing][200] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] sofia.c:7051 Remote SDP: 6b529c20-e5ae-4da8-a72f-cd11513c426e v=0 6b529c20-e5ae-4da8-a72f-cd11513c426e o=IPFax 0 0 IN IP4 199.182.208.17 6b529c20-e5ae-4da8-a72f-cd11513c426e s=SIP Fax Call 6b529c20-e5ae-4da8-a72f-cd11513c426e i=IPFax 6b529c20-e5ae-4da8-a72f-cd11513c426e c=IN IP4 199.182.208.17 6b529c20-e5ae-4da8-a72f-cd11513c426e t=0 0 6b529c20-e5ae-4da8-a72f-cd11513c426e m=audio 11480 RTP/AVP 0 6b529c20-e5ae-4da8-a72f-cd11513c426e a=rtpmap:0 PCMU/8000 6b529c20-e5ae-4da8-a72f-cd11513c426e a=ptime:20 6b529c20-e5ae-4da8-a72f-cd11513c426e 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] sofia.c:7041 Channel sofia/external-compact/7734136118@199.182.208.17:5060 entering state [ready][200] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:4433 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:3043 Set Codec sofia/external-compact/7734136118@199.182.208.17:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_codec.c:111 sofia/external-compact/7734136118@199.182.208.17:5060 Original read codec set to PCMU:0 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:4667 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event. 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:4696 sofia/external-compact/7734136118@199.182.208.17:5060 Set 2833 dtmf send payload to 101 recv payload to 101 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:6703 AUDIO RTP [sofia/external-compact/7734136118@199.182.208.17:5060] 64.16.240.36 port 17788 -> 199.182.208.17 port 11480 codec: 0 ms: 20 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_rtp.c:3878 Starting timer [soft] 160 bytes per 20ms 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:6928 Activating RTCP PORT 0 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_rtp.c:4264 RTCP send rate is: 10000 and packet rate is: 20000 Remote Port: 11481 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_rtp.c:2537 Setting RTCP remote addr to 199.182.208.17:11481 2 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:7009 sofia/external-compact/7734136118@199.182.208.17:5060 Set 2833 dtmf send payload to 101 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:7016 sofia/external-compact/7734136118@199.182.208.17:5060 Set 2833 dtmf receive payload to 101 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_media.c:7039 sofia/external-compact/7734136118@199.182.208.17:5060 Set rtp dtmf delay to 40 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [NOTICE] sofia.c:8157 Channel [sofia/external-compact/7734136118@199.182.208.17:5060] has been answered 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_channel.c:3772 (sofia/external-compact/7734136118@199.182.208.17:5060) Callstate Change RINGING -> ACTIVE 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_ivr_bridge.c:1566 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_EXCHANGE_MEDIA (Cur 831 Tot 2416174) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] switch_core_state_machine.c:653 (sofia/external-compact/7734136118@199.182.208.17:5060) State EXCHANGE_MEDIA 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:43.923420 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:44.163421 [DEBUG] switch_rtp.c:6994 Correct audio ip/port confirmed. 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:44.163421 [DEBUG] switch_rtp.c:1878 rtcp_stats_init: audio ssrc[6990] base_seq[22911] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.503420 [DEBUG] sofia.c:7041 Channel sofia/external-compact/7734136118@199.182.208.17:5060 entering state [received][100] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.503420 [DEBUG] sofia.c:7051 Remote SDP: 6b529c20-e5ae-4da8-a72f-cd11513c426e v=0 6b529c20-e5ae-4da8-a72f-cd11513c426e o=IPFax 0 1 IN IP4 199.182.208.17 6b529c20-e5ae-4da8-a72f-cd11513c426e s=SIP Fax Call 6b529c20-e5ae-4da8-a72f-cd11513c426e i=IPFax 6b529c20-e5ae-4da8-a72f-cd11513c426e c=IN IP4 199.182.208.17 6b529c20-e5ae-4da8-a72f-cd11513c426e t=0 0 6b529c20-e5ae-4da8-a72f-cd11513c426e m=image 11480 udptl t38 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxVersion:0 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38MaxBitRate:14400 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxRateManagement:transferredTCF 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxMaxBuffer:200 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxMaxDatagram:72 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxUdpEC:t38UDPRedundancy 6b529c20-e5ae-4da8-a72f-cd11513c426e 6b529c20-e5ae-4da8-a72f-cd11513c426e EXECUTE sofia/external-compact/7734136118@199.182.208.17:5060 t38_gateway(self nocng) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.503420 [DEBUG] mod_spandsp_fax.c:2068 sofia/internal/+13022523218@23.29.25.70 starting gateway mode to sofia/external-compact/7734136118@199.182.208.17:5060 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.503420 [DEBUG] mod_spandsp_fax.c:2086 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_EXCHANGE_MEDIA -> CS_RESET 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.503420 [DEBUG] switch_core_media.c:3963 sofia/external-compact/7734136118@199.182.208.17:5060 T38 ACCEPT on request 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.503420 [DEBUG] switch_core_media.c:4068 sofia/external-compact/7734136118@199.182.208.17:5060 T38 IS POSSIBLE on request 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_ivr_bridge.c:752 BRIDGE THREAD DONE [sofia/external-compact/7734136118@199.182.208.17:5060] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_state_machine.c:653 (sofia/external-compact/7734136118@199.182.208.17:5060) State EXCHANGE_MEDIA going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_RESET (Cur 829 Tot 2416208) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_state_machine.c:646 (sofia/external-compact/7734136118@199.182.208.17:5060) State RESET 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_sofia.c:161 sofia/external-compact/7734136118@199.182.208.17:5060 SOFIA RESET 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:2030 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_RESET -> CS_SOFT_EXECUTE 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_state_machine.c:646 (sofia/external-compact/7734136118@199.182.208.17:5060) State RESET going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_SOFT_EXECUTE (Cur 829 Tot 2416208) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_state_machine.c:656 (sofia/external-compact/7734136118@199.182.208.17:5060) State SOFT_EXECUTE 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [WARNING] mod_spandsp_fax.c:1136 sofia/external-compact/7734136118@199.182.208.17:5060 already has T.38 data 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1038 T38 SDP Origin = IPFax 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1039 T38FaxVersion = 0 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1040 T38MaxBitRate = 14400 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1041 T38FaxFillBitRemoval = 0 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1042 T38FaxTranscodingMMR = 0 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1043 T38FaxTranscodingJBIG = 0 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1044 T38FaxRateManagement = transferredTCF 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1045 T38FaxMaxBuffer = 200 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1046 T38FaxMaxDatagram = 72 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1047 T38FaxUdpEC = t38UDPRedundancy 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1048 T38VendorInfo = 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1049 ip = 199.182.208.17 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] mod_spandsp_fax.c:1051 port = 11480 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_media.c:9569 Remote address:port [199.182.208.17:11480] has not changed. 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_media.c:9208 sofia/external-compact/7734136118@199.182.208.17:5060 image media sdp: 6b529c20-e5ae-4da8-a72f-cd11513c426e v=0 6b529c20-e5ae-4da8-a72f-cd11513c426e o=FreeSWITCH 1485791715 1485791717 IN IP4 64.16.240.36 6b529c20-e5ae-4da8-a72f-cd11513c426e s=FreeSWITCH 6b529c20-e5ae-4da8-a72f-cd11513c426e c=IN IP4 64.16.240.36 6b529c20-e5ae-4da8-a72f-cd11513c426e t=0 0 6b529c20-e5ae-4da8-a72f-cd11513c426e m=image 17788 udptl t38 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxVersion:0 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38MaxBitRate:14400 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxRateManagement:transferredTCF 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxMaxBuffer:200 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxMaxDatagram:400 6b529c20-e5ae-4da8-a72f-cd11513c426e a=T38FaxUdpEC:t38UDPRedundancy 6b529c20-e5ae-4da8-a72f-cd11513c426e 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] switch_core_media.c:9569 Remote address:port [199.182.208.17:11480] has not changed. 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.523420 [DEBUG] sofia.c:7041 Channel sofia/external-compact/7734136118@199.182.208.17:5060 entering state [completed][200] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.543420 [DEBUG] mod_spandsp_fax.c:1766 sofia/external-compact/7734136118@199.182.208.17:5060 Error decoding UDPTL (172 bytes) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.563420 [DEBUG] sofia.c:7041 Channel sofia/external-compact/7734136118@199.182.208.17:5060 entering state [ready][200] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.583420 [DEBUG] mod_spandsp_fax.c:1766 sofia/external-compact/7734136118@199.182.208.17:5060 Error decoding UDPTL (172 bytes) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:51:47.603420 [DEBUG] mod_spandsp_fax.c:1766 sofia/external-compact/7734136118@199.182.208.17:5060 Error decoding UDPTL (172 bytes) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [NOTICE] sofia.c:1012 Hangup sofia/external-compact/7734136118@199.182.208.17:5060 [CS_SOFT_EXECUTE] [NORMAL_CLEARING] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [ERR] mod_spandsp_fax.c:650 INVALID WRITE: 26:3 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [ERR] mod_spandsp_fax.c:662 TERMINATING T30 STATE 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] mod_spandsp_fax.c:1777 sofia/external-compact/7734136118@199.182.208.17:5060 skip receive message [UNBRIDGE] (channel is hungup already) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:656 (sofia/external-compact/7734136118@199.182.208.17:5060) State SOFT_EXECUTE going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_HANGUP (Cur 870 Tot 2416830) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:850 (sofia/external-compact/7734136118@199.182.208.17:5060) Callstate Change ACTIVE -> HANGUP 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:852 (sofia/external-compact/7734136118@199.182.208.17:5060) State HANGUP 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] mod_sofia.c:438 Channel sofia/external-compact/7734136118@199.182.208.17:5060 hanging up, cause: NORMAL_CLEARING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:60 sofia/external-compact/7734136118@199.182.208.17:5060 Standard HANGUP, cause: NORMAL_CLEARING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:852 (sofia/external-compact/7734136118@199.182.208.17:5060) State HANGUP going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:619 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_HANGUP -> CS_REPORTING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:584 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_REPORTING (Cur 870 Tot 2416830) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [DEBUG] switch_core_state_machine.c:938 (sofia/external-compact/7734136118@199.182.208.17:5060) State REPORTING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.583423 [INFO] mod_json_cdr.c:271 Process [6b529c20-e5ae-4da8-a72f-cd11513c426e.cdr.json] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.603424 [ERR] mod_spandsp_fax.c:650 INVALID WRITE: 21:3 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.603424 [ERR] mod_spandsp_fax.c:662 TERMINATING T30 STATE 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_state_machine.c:174 sofia/external-compact/7734136118@199.182.208.17:5060 Standard REPORTING, cause: NORMAL_CLEARING 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_state_machine.c:938 (sofia/external-compact/7734136118@199.182.208.17:5060) State REPORTING going to sleep 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_state_machine.c:610 (sofia/external-compact/7734136118@199.182.208.17:5060) State Change CS_REPORTING -> CS_DESTROY 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_session.c:1647 Session 2416172 (sofia/external-compact/7734136118@199.182.208.17:5060) Locked, Waiting on external entities 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [NOTICE] switch_core_session.c:1665 Session 2416172 (sofia/external-compact/7734136118@199.182.208.17:5060) Ended 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external-compact/7734136118@199.182.208.17:5060 [CS_DESTROY] 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_state_machine.c:741 (sofia/external-compact/7734136118@199.182.208.17:5060) Running State Change CS_DESTROY (Cur 868 Tot 2416830) 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_state_machine.c:751 (sofia/external-compact/7734136118@199.182.208.17:5060) State DESTROY 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] mod_sofia.c:343 sofia/external-compact/7734136118@199.182.208.17:5060 SOFIA DESTROY 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_state_machine.c:181 sofia/external-compact/7734136118@199.182.208.17:5060 Standard DESTROY 6b529c20-e5ae-4da8-a72f-cd11513c426e 2017-01-30 20:52:47.643425 [DEBUG] switch_core_state_machine.c:751 (sofia/external-compact/7734136118@199.182.208.17:5060) State DESTROY going to sleep