FreeSWITCH Pastebin

Fax ECM Errors

From John A. Reed, 6 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/567b8ba6
Embed
Download Paste or View Raw
  1. freeswitch@ip-172-31-55-240> 2018-01-16 23:05:10.896861 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  2. 2018-01-16 23:05:11.256826 [INFO] mod_pocketsphinx.c:644 PocketSphinx Reloaded
  3. 2018-01-16 23:05:11.256826 [INFO] mod_enum.c:879 ENUM Reloaded
  4. 2018-01-16 23:05:11.256826 [DEBUG] mod_spandsp.c:680 Setting tone detector debug-level to : 9
  5. 2018-01-16 23:05:11.256826 [INFO] switch_time.c:1423 Timezone reloaded 530 definitions
  6. 2018-01-16 23:05:20.896861 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  7. 2018-01-16 23:05:21.416930 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  8. 2018-01-16 23:05:21.416930 [NOTICE] switch_channel.c:1104 New Channel sofia/external/12158258773 [ba2692d2-fb11-11e7-9319-455b975e6257]
  9. 2018-01-16 23:05:21.416930 [DEBUG] mod_sofia.c:4819 (sofia/external/12158258773) State Change CS_NEW -> CS_INIT
  10. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:584 (sofia/external/12158258773) Running State Change CS_INIT (Cur 1 Tot 1)
  11. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:627 (sofia/external/12158258773) State INIT
  12. 2018-01-16 23:05:21.416930 [ALERT] switch_core_state_machine.c:627 sofia/external/12158258773 Send KeyFrame
  13. 2018-01-16 23:05:21.416930 [DEBUG] mod_sofia.c:90 sofia/external/12158258773 SOFIA INIT
  14. 2018-01-16 23:05:21.416930 [DEBUG] sofia_glue.c:1295 sofia/external/12158258773 sending invite version: 1.6.19  64bit
  15. Local SDP:
  16. v=0
  17. o=FreeSWITCH 1516125843 1516125844 IN IP4 52.90.40.222
  18. s=FreeSWITCH
  19. c=IN IP4 52.90.40.222
  20. t=0 0
  21. m=audio 18078 RTP/AVP 0 8 3 101
  22. a=rtpmap:0 PCMU/8000
  23. a=rtpmap:8 PCMA/8000
  24. a=rtpmap:3 GSM/8000
  25. a=rtpmap:101 telephone-event/8000
  26. a=fmtp:101 0-16
  27. a=ptime:20
  28. a=sendrecv
  29.  
  30. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:40 sofia/external/12158258773 Standard INIT
  31. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:48 (sofia/external/12158258773) State Change CS_INIT -> CS_ROUTING
  32. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:627 (sofia/external/12158258773) State INIT going to sleep
  33. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:584 (sofia/external/12158258773) Running State Change CS_ROUTING (Cur 1 Tot 1)
  34. 2018-01-16 23:05:21.416930 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [calling][0]
  35. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:643 (sofia/external/12158258773) State ROUTING
  36. 2018-01-16 23:05:21.416930 [ALERT] switch_core_state_machine.c:643 sofia/external/12158258773 Send KeyFrame
  37. 2018-01-16 23:05:21.416930 [DEBUG] mod_sofia.c:143 sofia/external/12158258773 SOFIA ROUTING
  38. 2018-01-16 23:05:21.416930 [DEBUG] switch_ivr_originate.c:67 (sofia/external/12158258773) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  39. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:643 (sofia/external/12158258773) State ROUTING going to sleep
  40. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:584 (sofia/external/12158258773) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 1)
  41. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:662 (sofia/external/12158258773) State CONSUME_MEDIA
  42. 2018-01-16 23:05:21.416930 [ALERT] switch_core_state_machine.c:662 sofia/external/12158258773 Send KeyFrame
  43. 2018-01-16 23:05:21.416930 [DEBUG] switch_core_state_machine.c:662 (sofia/external/12158258773) State CONSUME_MEDIA going to sleep
  44. 2018-01-16 23:05:21.416930 [ALERT] switch_core_state_machine.c:701 sofia/external/12158258773 session thread sleep state: CS_CONSUME_MEDIA!
  45. 2018-01-16 23:05:21.416930 [ALERT] switch_core_state_machine.c:705 sofia/external/12158258773 session thread wake state: CS_CONSUME_MEDIA!
  46. 2018-01-16 23:05:21.416930 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [calling][0]
  47. 2018-01-16 23:05:21.416930 [ALERT] switch_core_state_machine.c:701 sofia/external/12158258773 session thread sleep state: CS_CONSUME_MEDIA!
  48. 2018-01-16 23:05:21.736879 [ALERT] switch_core_state_machine.c:705 sofia/external/12158258773 session thread wake state: CS_CONSUME_MEDIA!
  49. 2018-01-16 23:05:21.736879 [ALERT] sofia.c:1274 sofia/external/12158258773 Same Callee ID "Outbound Call" <12158258773>
  50. 2018-01-16 23:05:21.736879 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [proceeding][180]
  51. 2018-01-16 23:05:21.736879 [NOTICE] sofia.c:7192 Ring-Ready sofia/external/12158258773!
  52. 2018-01-16 23:05:21.736879 [DEBUG] switch_channel.c:3346 (sofia/external/12158258773) Callstate Change DOWN -> RINGING
  53. 2018-01-16 23:05:21.736879 [ALERT] sofia.c:7192 sofia/external/12158258773 receive message [RING_EVENT]
  54. 2018-01-16 23:05:21.736879 [ALERT] switch_core_state_machine.c:701 sofia/external/12158258773 session thread sleep state: CS_CONSUME_MEDIA!
  55. 2018-01-16 23:05:21.816928 [ALERT] switch_core_state_machine.c:705 sofia/external/12158258773 session thread wake state: CS_CONSUME_MEDIA!
  56. 2018-01-16 23:05:21.816928 [ALERT] switch_core_media.c:447 Looking for zrtp-hash
  57. 2018-01-16 23:05:21.816928 [ALERT] switch_core_media.c:402 Deciding whether to pass zrtp-hash between legs
  58. 2018-01-16 23:05:21.816928 [ALERT] switch_core_media.c:404 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  59. 2018-01-16 23:05:21.816928 [ALERT] sofia.c:1274 sofia/external/12158258773 Same Callee ID "Outbound Call" <12158258773>
  60. 2018-01-16 23:05:21.816928 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [completing][200]
  61. 2018-01-16 23:05:21.816928 [DEBUG] sofia.c:7094 Remote SDP:
  62. v=0
  63. o=Sippy 3369124320713125429 1 IN IP4 8.34.182.111
  64. s=iFAX
  65. t=0 0
  66. m=audio 21516 RTP/AVP 0 101
  67. c=IN IP4 8.34.182.100
  68. a=rtpmap:0 PCMU/8000
  69. a=rtpmap:101 telephone-event/8000
  70. a=fmtp:101 0-16
  71. a=silenceSupp:off - - - -
  72. a=ptime:20
  73.  
  74. 2018-01-16 23:05:21.816928 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [ready][200]
  75. 2018-01-16 23:05:21.816928 [ALERT] switch_core_media.c:447 Looking for zrtp-hash
  76. 2018-01-16 23:05:21.816928 [ALERT] switch_core_media.c:402 Deciding whether to pass zrtp-hash between legs
  77. 2018-01-16 23:05:21.816928 [ALERT] switch_core_media.c:404 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  78. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  79. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  80. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  81. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  82. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  83. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/12158258773 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  84. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_codec.c:111 sofia/external/12158258773 Original read codec set to PCMU:0
  85. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  86. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:4767 sofia/external/12158258773 Set 2833 dtmf send payload to 101 recv payload to 101
  87. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/12158258773] 172.31.55.240 port 18078 -> 8.34.182.100 port 21516 codec: 0 ms: 20
  88. 2018-01-16 23:05:21.816928 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  89. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:7179 sofia/external/12158258773 Set 2833 dtmf send payload to 101
  90. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:7186 sofia/external/12158258773 Set 2833 dtmf receive payload to 101
  91. 2018-01-16 23:05:21.816928 [DEBUG] switch_core_media.c:7209 sofia/external/12158258773 Set rtp dtmf delay to 40
  92. 2018-01-16 23:05:21.816928 [NOTICE] sofia.c:8218 Channel [sofia/external/12158258773] has been answered
  93. 2018-01-16 23:05:21.816928 [DEBUG] switch_channel.c:3773 (sofia/external/12158258773) Callstate Change RINGING -> ACTIVE
  94. 2018-01-16 23:05:21.816928 [ALERT] sofia.c:8218 sofia/external/12158258773 receive message [ANSWER_EVENT]
  95. 2018-01-16 23:05:21.816928 [ALERT] switch_core_state_machine.c:701 sofia/external/12158258773 session thread sleep state: CS_CONSUME_MEDIA!
  96. 2018-01-16 23:05:21.856943 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/external/12158258773]
  97. 2018-01-16 23:05:21.856943 [INFO] switch_channel.c:3129 sofia/external/12158258773 Flipping CID from "" <0000000000> to "Outbound Call" <12158258773>
  98. 2018-01-16 23:05:21.856943 [DEBUG] mod_commands.c:4862 (sofia/external/12158258773) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
  99. 2018-01-16 23:05:21.856943 [ALERT] switch_core_state_machine.c:705 sofia/external/12158258773 session thread wake state: CS_CONSUME_MEDIA!
  100. 2018-01-16 23:05:21.856943 [ALERT] switch_ivr_originate.c:4038 sofia/external/12158258773 receive message [AUDIO_SYNC]
  101. 2018-01-16 23:05:21.856943 [DEBUG] switch_core_state_machine.c:584 (sofia/external/12158258773) Running State Change CS_EXECUTE (Cur 1 Tot 1)
  102. 2018-01-16 23:05:21.856943 [DEBUG] switch_core_state_machine.c:650 (sofia/external/12158258773) State EXECUTE
  103. 2018-01-16 23:05:21.856943 [ALERT] switch_core_state_machine.c:650 sofia/external/12158258773 Send KeyFrame
  104. 2018-01-16 23:05:21.856943 [DEBUG] mod_sofia.c:198 sofia/external/12158258773 SOFIA EXECUTE
  105. 2018-01-16 23:05:21.856943 [DEBUG] switch_core_state_machine.c:328 sofia/external/12158258773 Standard EXECUTE
  106. 2018-01-16 23:05:21.856943 [ALERT] switch_core_session.c:2587 sofia/external/12158258773 Send KeyFrame
  107. EXECUTE sofia/external/12158258773 txfax(/usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif)
  108. 2018-01-16 23:05:21.856943 [ALERT] switch_core_session.c:2800 sofia/external/12158258773 receive message [APPLICATION_EXEC]
  109. 2018-01-16 23:05:21.856943 [DEBUG] mod_spandsp_fax.c:1448 Raw read codec activation Success L16 20000
  110. 2018-01-16 23:05:21.856943 [DEBUG] switch_core_codec.c:223 sofia/external/12158258773 Push codec L16:100
  111. 2018-01-16 23:05:21.856943 [DEBUG] mod_spandsp_fax.c:1466 Raw write codec activation Success L16
  112. 2018-01-16 23:05:21.856943 [ALERT] switch_ivr.c:217 sofia/external/12158258773 receive message [AUDIO_SYNC]
  113. 2018-01-16 23:05:21.856943 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  114. 2018-01-16 23:05:21.856943 [ALERT] switch_core_io.c:416 sofia/external/12158258773 receive message [TRANSCODING_NECESSARY]
  115. 2018-01-16 23:05:22.216925 [ALERT] switch_rtp.c:1757 Calculated Instantaneous Packet Delay Variation: audio packet 2.000000
  116. 2018-01-16 23:05:22.696862 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state T
  117. 2018-01-16 23:05:24.096861 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [received][100]
  118. 2018-01-16 23:05:24.096861 [DEBUG] sofia.c:7094 Remote SDP:
  119. v=0
  120. o=Sippy 3369124320713125429 2 IN IP4 8.34.182.111
  121. s=iFAX
  122. t=0 0
  123. m=image 21516 udptl t38
  124. c=IN IP4 8.34.182.100
  125. a=T38FaxVersion:0
  126. a=T38MaxBitRate:14400
  127. a=T38FaxFillBitRemoval
  128. a=T38FaxRateManagement:transferredTCF
  129. a=T38FaxMaxBuffer:2000
  130. a=T38FaxMaxDatagram:400
  131. a=T38FaxUdpEC:t38UDPRedundancy
  132.  
  133. 2018-01-16 23:05:24.096861 [ALERT] switch_core_media.c:447 Looking for zrtp-hash
  134. 2018-01-16 23:05:24.096861 [ALERT] switch_core_media.c:402 Deciding whether to pass zrtp-hash between legs
  135. 2018-01-16 23:05:24.096861 [ALERT] switch_core_media.c:404 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  136. 2018-01-16 23:05:24.096861 [DEBUG] switch_core_media.c:4041 sofia/external/12158258773 T38 ACCEPT on request
  137. 2018-01-16 23:05:24.096861 [DEBUG] switch_core_media.c:4146 sofia/external/12158258773 T38 IS POSSIBLE on request
  138. 2018-01-16 23:05:24.116796 [WARNING] mod_spandsp_fax.c:1138 sofia/external/12158258773 already has T.38 data
  139. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1040 T38 SDP Origin = Sippy
  140. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1041 T38FaxVersion = 0
  141. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1042 T38MaxBitRate = 14400
  142. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1043 T38FaxFillBitRemoval = 1
  143. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1044 T38FaxTranscodingMMR = 0
  144. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1045 T38FaxTranscodingJBIG = 0
  145. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1046 T38FaxRateManagement = 'transferredTCF'
  146. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1047 T38FaxMaxBuffer = 2000
  147. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1048 T38FaxMaxDatagram = 400
  148. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1049 T38FaxUdpEC = 't38UDPRedundancy'
  149. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1050 T38VendorInfo = ''
  150. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1051 ip = '8.34.182.100'
  151. 2018-01-16 23:05:24.116796 [DEBUG] mod_spandsp_fax.c:1053 port = 21516
  152. 2018-01-16 23:05:24.116796 [ALERT] mod_spandsp_fax.c:1096 sofia/external/12158258773 receive message [T38_DESCRIPTION]
  153. 2018-01-16 23:05:24.116796 [DEBUG] switch_core_media.c:9750 Remote address:port [8.34.182.100:21516] has not changed.
  154. 2018-01-16 23:05:24.116796 [DEBUG] switch_core_media.c:9389 sofia/external/12158258773 image media sdp:
  155. v=0
  156. o=FreeSWITCH 1516125843 1516125845 IN IP4 52.90.40.222
  157. s=FreeSWITCH
  158. c=IN IP4 52.90.40.222
  159. t=0 0
  160. m=image 18078 udptl t38
  161. a=T38FaxVersion:0
  162. a=T38MaxBitRate:14400
  163. a=T38FaxFillBitRemoval
  164. a=T38FaxRateManagement:transferredTCF
  165. a=T38FaxMaxBuffer:2000
  166. a=T38FaxMaxDatagram:400
  167. a=T38FaxUdpEC:t38UDPRedundancy
  168.  
  169. 2018-01-16 23:05:24.116796 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [completed][200]
  170. 2018-01-16 23:05:24.116796 [ALERT] mod_spandsp_fax.c:771 sofia/external/12158258773 receive message [UDPTL_MODE]
  171. 2018-01-16 23:05:24.116796 [DEBUG] switch_core_media.c:9750 Remote address:port [8.34.182.100:21516] has not changed.
  172. 2018-01-16 23:05:24.156842 [DEBUG] sofia.c:7084 Channel sofia/external/12158258773 entering state [ready][200]
  173. 2018-01-16 23:05:24.216870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present
  174. 2018-01-16 23:05:26.656857 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present
  175. 2018-01-16 23:05:26.656857 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state T
  176. 2018-01-16 23:05:26.656857 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state T
  177. 2018-01-16 23:05:26.656857 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase A_CNG to B_RX
  178. 2018-01-16 23:05:26.656857 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4
  179. 2018-01-16 23:05:26.656857 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0
  180. 2018-01-16 23:05:28.236801 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CSI - CRC OK (clean)
  181. 2018-01-16 23:05:28.236801 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop none (0 remaining)
  182. 2018-01-16 23:05:28.236801 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  CSI without final frame tag
  183. 2018-01-16 23:05:28.236801 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 03 40 73 6e 6f 69 74 75 6c 6f 53 58 41 46 69 20 20 20 20 20 20 20
  184. 2018-01-16 23:05:28.236801 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Remote gave CSI as: "iFAXSolutions"
  185. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type DIS - CRC OK (clean)
  186. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop none (0 remaining)
  187. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  DIS with final frame tag
  188. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 13 80 00 ee f8 c4 80 10
  189. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state T
  190. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX
  191. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS:
  192. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
  193. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
  194. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= 3G mobile network: Not set
  195. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= V.8 capabilities: Not set
  196. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Preferred octets: 256 octets
  197. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
  198. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..1.= Can receive fax: Set
  199. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
  200. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
  201. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= 2-D coding: Set
  202. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..00= Recording width: 215mm +- 1%
  203. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 10..= Recording length: Unlimited
  204. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
  205. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  206. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
  207. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .1..= Error correction mode (ECM): ECM
  208. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .1.. ....= T.6 coding: Set
  209. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  210. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= "Field not valid" supported: Not set
  211. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Multiple selective polling: Not set
  212. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Polled sub-address: Not set
  213. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= T.43 coding: Not set
  214. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= Plane interleave: Not set
  215. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
  216. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
  217. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  218. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= R8x15.4lines/mm: Not set
  219. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
  220. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
  221. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= Inch-based resolution preferred: Not set
  222. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...1 ....= Metric-based resolution preferred: Set
  223. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
  224. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Selective polling: Not set
  225. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   0... ....= Extension indicator: Not set
  226. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:317 === Negotiation Result =======================================================
  227. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:318 Remote station id: iFAXSolutions
  228. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:319 Local station id:  SpanDSP Fax Ident
  229. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:320 Transfer Rate:     14400
  230. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:322 ECM status         off
  231. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:323 remote country:
  232. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:324 remote vendor:
  233. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:325 remote model:
  234. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:327 ==============================================================================
  235. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Trying to send file '/usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif'
  236. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start sending document
  237. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Choose image type bi-level (0), compression T.4 2-D (4)
  238. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Minimum bits per row will be 0
  239. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting page 1 of transfer
  240. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Image width is A4 at 8040dpm x 3850dpm
  241. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Put document with modem (7) V.17 at 14400bps
  242. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state T to D
  243. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident 'SpanDSP Fax Ident'
  244. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  TSI without final frame tag
  245. 2018-01-16 23:05:28.576863 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 03 43 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20
  246. 2018-01-16 23:05:28.596934 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state D
  247. 2018-01-16 23:05:28.596934 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state D
  248. 2018-01-16 23:05:28.596934 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX
  249. 2018-01-16 23:05:28.596934 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  250. 2018-01-16 23:05:28.596934 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4
  251. 2018-01-16 23:05:28.596934 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present
  252. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state D
  253. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DCS:
  254. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
  255. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
  256. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= 3G mobile network: Not set
  257. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..1.= Receive fax: Set
  258. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..10 00..= Selected data signalling rate: V.17 14400bps
  259. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Not set
  260. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= 2-D coding: Set
  261. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..00= Recording width: 215mm +- 1%
  262. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 10..= Recording length: Unlimited
  263. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .111 ....= Minimum scan line time: 0ms
  264. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   0... ....= Extension indicator: Not set
  265. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  DCS with final frame tag
  266. 2018-01-16 23:05:30.296870 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 13 83 00 a2 78
  267. 2018-01-16 23:05:30.576855 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state D
  268. 2018-01-16 23:05:30.576855 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state D
  269. 2018-01-16 23:05:30.576855 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state D to D_TCF
  270. 2018-01-16 23:05:30.576855 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to C_NON_ECM_TX
  271. 2018-01-16 23:05:30.576855 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  272. 2018-01-16 23:05:30.576855 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  273. 2018-01-16 23:05:30.576855 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 7
  274. 2018-01-16 23:05:30.896865 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  275. 2018-01-16 23:05:33.936864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase C_NON_ECM_TX, state D_TCF
  276. 2018-01-16 23:05:33.936864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase C_NON_ECM_TX to B_RX
  277. 2018-01-16 23:05:33.936864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4
  278. 2018-01-16 23:05:33.936864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0
  279. 2018-01-16 23:05:33.936864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4
  280. 2018-01-16 23:05:33.936864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state D_TCF to D_POST_TCF
  281. 2018-01-16 23:05:34.756788 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present
  282. 2018-01-16 23:05:34.756788 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state D_POST_TCF
  283. 2018-01-16 23:05:34.756788 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state D_POST_TCF
  284. 2018-01-16 23:05:34.756788 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged
  285. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CFR - CRC OK (clean)
  286. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (15520 remaining)
  287. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  CFR with final frame tag
  288. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 13 84
  289. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state D_POST_TCF
  290. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Trainability test succeeded
  291. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state D_POST_TCF to I
  292. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase C_NON_ECM_TX
  293. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state I
  294. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state I
  295. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to C_NON_ECM_TX
  296. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  297. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  298. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 7
  299. 2018-01-16 23:05:35.816860 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present
  300. 2018-01-16 23:05:40.896859 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  301. 2018-01-16 23:05:50.896860 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  302. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase C_NON_ECM_TX, state I
  303. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase C_NON_ECM_TX to D_TX
  304. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  305. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4
  306. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state I to II_Q
  307. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No more pages to send
  308. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  EOP with final frame tag
  309. 2018-01-16 23:05:53.416805 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 13 2f
  310. 2018-01-16 23:05:54.736806 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state II_Q
  311. 2018-01-16 23:05:54.736806 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state II_Q
  312. 2018-01-16 23:05:54.736806 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_TX to D_RX
  313. 2018-01-16 23:05:54.736806 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4
  314. 2018-01-16 23:05:54.736806 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0
  315. 2018-01-16 23:05:54.736806 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4
  316. 2018-01-16 23:05:55.296859 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present
  317. 2018-01-16 23:05:55.296859 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state II_Q
  318. 2018-01-16 23:05:55.296859 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state II_Q
  319. 2018-01-16 23:05:55.296859 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged
  320. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type MCF - CRC OK (clean)
  321. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (15680 remaining)
  322. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  MCF with final frame tag
  323. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 13 8c
  324. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state II_Q
  325. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:428 ==== Page Sent ===============================================================
  326. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:429 Page no = 1
  327. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:430 Image type = bi-level (bi-level in the file)
  328. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:431 Image size = 1728 x 1094 pixels (1728 x 1078 pixels in the file)
  329. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:432 Image resolution = 8040/m x 3850/m (8031/m x 3858/m in the file)
  330. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:433 Compression = T.4 2-D (4)
  331. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:434 Compressed image size = 31089 bytes
  332. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:435 Bad rows = 0
  333. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:436 Longest bad row run = 0
  334. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:437 ==============================================================================
  335. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase D_TX
  336. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state II_Q to C
  337. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  DCN with final frame tag
  338. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 13 fb
  339. 2018-01-16 23:05:56.336861 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Success - delivered 1 pages
  340. 2018-01-16 23:05:56.356864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase D_RX, state C
  341. 2018-01-16 23:05:56.356864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state C
  342. 2018-01-16 23:05:56.356864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_RX to D_TX
  343. 2018-01-16 23:05:56.356864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  344. 2018-01-16 23:05:56.356864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4
  345. 2018-01-16 23:05:56.356864 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present
  346. 2018-01-16 23:05:57.516854 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C
  347. 2018-01-16 23:05:57.516854 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C
  348. 2018-01-16 23:05:57.516854 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting final pause before disconnecting
  349. 2018-01-16 23:05:57.516854 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_TX to E
  350. 2018-01-16 23:05:57.516854 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  351. 2018-01-16 23:05:57.516854 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 1
  352. 2018-01-16 23:05:57.516854 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state C to B
  353. 2018-01-16 23:05:57.836793 [NOTICE] sofia.c:1012 Hangup sofia/external/12158258773 [CS_EXECUTE] [NORMAL_CLEARING]
  354. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:496 ==============================================================================
  355. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:500 Fax successfully sent.
  356. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:513 Remote station id: iFAXSolutions
  357. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:514 Local station id:  SpanDSP Fax Ident
  358. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 1
  359. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:517 Total fax pages:   1
  360. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:518 Image resolution:  8040x3850
  361. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate:     14400
  362. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:521 ECM status         off
  363. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:522 remote country:
  364. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:523 remote vendor:
  365. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:524 remote model:
  366. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:526 ==============================================================================
  367. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state B to CALL_FINISHED
  368. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase E to CALL_FINISHED
  369. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 9
  370. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 9
  371. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T FAX exchange complete
  372. 2018-01-16 23:05:57.836793 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Call completed
  373. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_codec.c:248 sofia/external/12158258773 Restore previous codec PCMU:0.
  374. 2018-01-16 23:05:57.836793 [ALERT] switch_core_session.c:2815 sofia/external/12158258773 receive message [APPLICATION_EXEC_COMPLETE]
  375. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_session.c:2815 sofia/external/12158258773 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  376. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_state_machine.c:650 (sofia/external/12158258773) State EXECUTE going to sleep
  377. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_state_machine.c:584 (sofia/external/12158258773) Running State Change CS_HANGUP (Cur 1 Tot 1)
  378. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_state_machine.c:850 (sofia/external/12158258773) Callstate Change ACTIVE -> HANGUP
  379. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_state_machine.c:852 (sofia/external/12158258773) State HANGUP
  380. 2018-01-16 23:05:57.836793 [ALERT] switch_core_state_machine.c:852 sofia/external/12158258773 Send KeyFrame
  381. 2018-01-16 23:05:57.836793 [DEBUG] mod_sofia.c:438 Channel sofia/external/12158258773 hanging up, cause: NORMAL_CLEARING
  382. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_state_machine.c:60 sofia/external/12158258773 Standard HANGUP, cause: NORMAL_CLEARING
  383. 2018-01-16 23:05:57.836793 [DEBUG] switch_core_state_machine.c:852 (sofia/external/12158258773) State HANGUP going to sleep
  384. 2018-01-16 23:05:57.836793 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f02101344d0 Connected.
  385. 2018-01-16 23:05:57.836793 [INFO] switch_cpp.cpp:1365 [FAX] info:
  386. 'direction: outbound
  387. is_outbound: true
  388. uuid: ba2692d2-fb11-11e7-9319-455b975e6257
  389. session_id: 1
  390. sip_gateway_name: 6f98ceb1-1ad0-478c-a524-69e6808b93b9
  391. sip_invite_domain: sip.t38fax.com
  392. sip_profile_name: gateway
  393. video_media_flow: sendrecv
  394. audio_media_flow: sendrecv
  395. channel_name: sofia/external/12158258773
  396. sip_destination_url: sip%3A12158258773%40sip.t38fax.com%3A5080
  397. for_fax: 1
  398. accountcode: 52.90.40.222
  399. sip_h_X-accountcode: 52.90.40.222
  400. domain_uuid: 76966341-7249-4fc3-98db-c64af81bc376
  401. domain_name: 52.90.40.222
  402. fax_file: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  403. fax_enable_t38: true
  404. fax_enable_t38_request: true
  405. ignore_early_media: true
  406. fax_uri: sofia/gateway/6f98ceb1-1ad0-478c-a524-69e6808b93b9/12158258773
  407. fax_retry_attempts: 1
  408. fax_retry_limit: 20
  409. fax_retry_sleep: 180
  410. fax_verbose: true
  411. fax_use_ecm: off
  412. api_hangup_hook: lua%20fax_retry.lua
  413. originate_early_media: false
  414. sip_outgoing_contact_uri: %3Csip%3Agw%2B6f98ceb1-1ad0-478c-a524-69e6808b93b9%4052.90.40.222%3A5080%3Btransport%3Dudp%3Bgw%3D6f98ceb1-1ad0-478c-a524-69e6808b93b9%3E
  415. sip_req_uri: 12158258773%40sip.t38fax.com%3A5080
  416. sofia_profile_name: external
  417. recovery_profile_name: external
  418. sip_local_network_addr: 52.90.40.222
  419. sip_reply_host: 8.34.182.111
  420. sip_reply_port: 5080
  421. sip_network_ip: 8.34.182.111
  422. sip_network_port: 5080
  423. ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  424. sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20INFO,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20UPDATE
  425. sip_invite_route_uri: %3Csip%3A8.34.182.111%3A5080%3Btransport%3Dudp%3Blr%3Bdrr%3E,%3Csip%3A8.34.182.111%3A5060%3Btransport%3Dudp%3Blr%3Bdrr%3E
  426. sip_invite_record_route: %3Csip%3A8.34.182.111%3A5060%3Btransport%3Dudp%3Blr%3Bdrr%3E,%3Csip%3A8.34.182.111%3A5080%3Btransport%3Dudp%3Blr%3Bdrr%3E
  427. sip_from_user: 0000000000
  428. sip_from_uri: 0000000000%40sip.t38fax.com
  429. sip_from_host: sip.t38fax.com
  430. sip_to_user: 12158258773
  431. sip_to_port: 5080
  432. sip_to_uri: 12158258773%40sip.t38fax.com%3A5080
  433. sip_to_host: sip.t38fax.com
  434. sip_contact_user: nobody
  435. sip_contact_port: 5061
  436. sip_contact_uri: nobody%408.34.182.111%3A5061
  437. sip_contact_host: 8.34.182.111
  438. rtp_audio_recv_pt: 0
  439. rtp_use_codec_name: PCMU
  440. rtp_use_codec_rate: 8000
  441. rtp_use_codec_ptime: 20
  442. rtp_use_codec_channels: 1
  443. rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
  444. original_read_codec: PCMU
  445. original_read_rate: 8000
  446. write_codec: PCMU
  447. write_rate: 8000
  448. dtmf_type: rfc2833
  449. local_media_ip: 172.31.55.240
  450. local_media_port: 18078
  451. advertised_media_ip: 52.90.40.222
  452. rtp_use_timer_name: soft
  453. rtp_use_pt: 0
  454. rtp_use_ssrc: 1852424801
  455. rtp_2833_send_payload: 101
  456. rtp_2833_recv_payload: 101
  457. remote_media_ip: 8.34.182.100
  458. remote_media_port: 21516
  459. endpoint_disposition: ANSWER
  460. pre_transfer_caller_id_number: 0000000000
  461. call_uuid: ba2692d2-fb11-11e7-9319-455b975e6257
  462. current_application_data: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  463. current_application: txfax
  464. switch_r_sdp: v%3D0%0D%0Ao%3DSippy%203369124320713125429%202%20IN%20IP4%208.34.182.111%0D%0As%3DiFAX%0D%0At%3D0%200%0D%0Am%3Dimage%2021516%20udptl%20t38%0D%0Ac%3DIN%20IP4%208.34.182.100%0D%0Aa%3DT38FaxVersion%3A0%0D%0Aa%3DT38MaxBitRate%3A14400%0D%0Aa%3DT38FaxFillBitRemoval%0D%0Aa%3DT38FaxRateManagement%3AtransferredTCF%0D%0Aa%3DT38FaxMaxBuffer%3A2000%0D%0Aa%3DT38FaxMaxDatagram%3A400%0D%0Aa%3DT38FaxUdpEC%3At38UDPRedundancy%0D%0A
  465. rtp_use_codec_string: PCMU,PCMA,GSM
  466. has_t38: true
  467. rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201516125843%201516125845%20IN%20IP4%2052.90.40.222%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2052.90.40.222%0D%0At%3D0%200%0D%0Am%3Dimage%2018078%20udptl%20t38%0D%0Aa%3DT38FaxVersion%3A0%0D%0Aa%3DT38MaxBitRate%3A14400%0D%0Aa%3DT38FaxFillBitRemoval%0D%0Aa%3DT38FaxRateManagement%3AtransferredTCF%0D%0Aa%3DT38FaxMaxBuffer%3A2000%0D%0Aa%3DT38FaxMaxDatagram%3A400%0D%0Aa%3DT38FaxUdpEC%3At38UDPRedundancy%0D%0A
  468. fax_v17_disabled: 0
  469. fax_ecm_requested: 0
  470. fax_filename: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  471. sip_to_tag: HZXUvmZ2t83gH
  472. sip_from_tag: 5hztxl73e4pjapwj.i
  473. sip_cseq: 580
  474. sip_call_id: 91855555-75b4-1236-4ea9-12266285acf2
  475. sip_recover_contact: %3Csip%3A8.34.182.111%3A5061%3E
  476. sip_full_via: SIP/2.0/UDP%208.34.182.111%3A5080%3Bbranch%3Dz9hG4bK-524287-1---432f9c22e291825f%3Brport%3D5080,SIP/2.0/UDP%208.34.182.111%3A5061%3Brport%3D5061%3Bbranch%3Dz9hG4bK-wg7zipzy7vbj6qva
  477. sip_recover_via: SIP/2.0/UDP%208.34.182.111%3A5080%3Bbranch%3Dz9hG4bK-524287-1---432f9c22e291825f%3Brport%3D5080,SIP/2.0/UDP%208.34.182.111%3A5061%3Brport%3D5061%3Bbranch%3Dz9hG4bK-wg7zipzy7vbj6qva
  478. sip_full_from: %3Csip%3A12158258773%40sip.t38fax.com%3A5080%3E%3Btag%3D5hztxl73e4pjapwj.i
  479. sip_full_to: %3Csip%3A0000000000%40sip.t38fax.com%3E%3Btag%3DHZXUvmZ2t83gH
  480. fax_file_image_resolution: 8031x3858
  481. fax_image_pixel_size: 1728x1094
  482. fax_file_image_pixel_size: 1728x1078
  483. fax_longest_bad_row_run: 0
  484. fax_encoding: 4
  485. fax_encoding_name: T.4%202-D
  486. sip_term_status: 200
  487. proto_specific_hangup_cause: sip%3A200
  488. sip_term_cause: 16
  489. sip_user_agent: Sippy
  490. sip_hangup_disposition: recv_bye
  491. fax_success: 1
  492. fax_result_code: 0
  493. fax_result_text: OK
  494. fax_ecm_used: off
  495. fax_local_station_id: SpanDSP%20Fax%20Ident
  496. fax_remote_station_id: iFAXSolutions
  497. fax_document_transferred_pages: 1
  498. fax_document_total_pages: 1
  499. fax_image_resolution: 8040x3850
  500. fax_image_size: 31089
  501. fax_bad_rows: 0
  502. fax_transfer_rate: 14400
  503. read_codec: PCMU
  504. read_rate: 8000
  505. hangup_cause: NORMAL_CLEARING
  506. hangup_cause_q850: 16
  507. digits_dialed: none
  508. start_stamp: 2018-01-16%2023%3A05%3A21
  509. profile_start_stamp: 2018-01-16%2023%3A05%3A21
  510. answer_stamp: 2018-01-16%2023%3A05%3A21
  511. progress_stamp: 2018-01-16%2023%3A05%3A21
  512. end_stamp: 2018-01-16%2023%3A05%3A57
  513. start_epoch: 1516143921
  514. start_uepoch: 1516143921416930
  515. profile_start_epoch: 1516143921
  516. profile_start_uepoch: 1516143921416930
  517. answer_epoch: 1516143921
  518. answer_uepoch: 1516143921816928
  519. bridge_epoch: 0
  520. bridge_uepoch: 0
  521. last_hold_epoch: 0
  522. last_hold_uepoch: 0
  523. hold_accum_seconds: 0
  524. hold_accum_usec: 0
  525. hold_accum_ms: 0
  526. resurrect_epoch: 0
  527. resurrect_uepoch: 0
  528. progress_epoch: 1516143921
  529. progress_uepoch: 1516143921736879
  530. progress_media_epoch: 0
  531. progress_media_uepoch: 0
  532. end_epoch: 1516143957
  533. end_uepoch: 1516143957836793
  534. last_app: txfax
  535. last_arg: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  536. caller_id: %22Outbound%20Call%22%20%3C12158258773%3E
  537. duration: 36
  538. billsec: 36
  539. progresssec: 0
  540. answersec: 0
  541. waitsec: 0
  542. progress_mediasec: 0
  543. flow_billsec: 36
  544. mduration: 36420
  545. billmsec: 36020
  546. progressmsec: 320
  547. answermsec: 400
  548. waitmsec: 0
  549. progress_mediamsec: 0
  550. flow_billmsec: 36420
  551. uduration: 36419863
  552. billusec: 36019865
  553. progressusec: 319949
  554. answerusec: 399998
  555. waitusec: 0
  556. progress_mediausec: 0
  557. flow_billusec: 36419863
  558. rtp_audio_in_raw_bytes: 22984
  559. rtp_audio_in_media_bytes: 21608
  560. rtp_audio_in_packet_count: 203
  561. rtp_audio_in_media_packet_count: 195
  562. rtp_audio_in_skip_packet_count: 14
  563. rtp_audio_in_jitter_packet_count: 0
  564. rtp_audio_in_dtmf_packet_count: 0
  565. rtp_audio_in_cng_packet_count: 0
  566. rtp_audio_in_flush_packet_count: 8
  567. rtp_audio_in_largest_jb_size: 0
  568. rtp_audio_in_jitter_min_variance: 20.09
  569. rtp_audio_in_jitter_max_variance: 80.36
  570. rtp_audio_in_jitter_loss_rate: 0.00
  571. rtp_audio_in_jitter_burst_rate: 0.00
  572. rtp_audio_in_mean_interval: 20.20
  573. rtp_audio_in_flaw_total: 0
  574. rtp_audio_in_quality_percentage: 100.00
  575. rtp_audio_in_mos: 4.50
  576. rtp_audio_out_raw_bytes: 175535
  577. rtp_audio_out_media_bytes: 175535
  578. rtp_audio_out_packet_count: 818
  579. rtp_audio_out_media_packet_count: 818
  580. rtp_audio_out_skip_packet_count: 0
  581. rtp_audio_out_dtmf_packet_count: 0
  582. rtp_audio_out_cng_packet_count: 0
  583. rtp_audio_rtcp_packet_count: 0
  584. rtp_audio_rtcp_octet_count: 0
  585. Channel-State: CS_HANGUP
  586. Channel-Call-State: HANGUP
  587. Channel-State-Number: 10
  588. Channel-Name: sofia/external/12158258773
  589. Unique-ID: ba2692d2-fb11-11e7-9319-455b975e6257
  590. Call-Direction: outbound
  591. Presence-Call-Direction: outbound
  592. Channel-HIT-Dialplan: true
  593. Channel-Call-UUID: ba2692d2-fb11-11e7-9319-455b975e6257
  594. Answer-State: hangup
  595. Hangup-Cause: NORMAL_CLEARING
  596. Channel-Read-Codec-Name: PCMU
  597. Channel-Read-Codec-Rate: 8000
  598. Channel-Read-Codec-Bit-Rate: 64000
  599. Channel-Write-Codec-Name: PCMU
  600. Channel-Write-Codec-Rate: 8000
  601. Channel-Write-Codec-Bit-Rate: 64000
  602. Caller-Direction: outbound
  603. Caller-Logical-Direction: outbound
  604. Caller-Caller-ID-Name: Outbound%20Call
  605. Caller-Caller-ID-Number: 12158258773
  606. Caller-Orig-Caller-ID-Number: 0000000000
  607. Caller-Callee-ID-Number: 0000000000
  608. Caller-Network-Addr: 8.34.182.111
  609. Caller-ANI: 0000000000
  610. Caller-Destination-Number: 12158258773
  611. Caller-Unique-ID: ba2692d2-fb11-11e7-9319-455b975e6257
  612. Caller-Source: src/switch_ivr_originate.c
  613. Caller-Context: default
  614. Caller-Channel-Name: sofia/external/12158258773
  615. Caller-Profile-Index: 1
  616. Caller-Profile-Created-Time: 1516143921416930
  617. Caller-Channel-Created-Time: 1516143921416930
  618. Caller-Channel-Answered-Time: 1516143921816928
  619. Caller-Channel-Progress-Time: 1516143921736879
  620. Caller-Channel-Progress-Media-Time: 0
  621. Caller-Channel-Hangup-Time: 1516143957836793
  622. Caller-Channel-Transfer-Time: 0
  623. Caller-Channel-Resurrect-Time: 0
  624. Caller-Channel-Bridged-Time: 0
  625. Caller-Channel-Last-Hold: 0
  626. Caller-Channel-Hold-Accum: 0
  627. Caller-Screen-Bit: true
  628. Caller-Privacy-Hide-Name: false
  629. Caller-Privacy-Hide-Number: false
  630. variable_direction: outbound
  631. variable_is_outbound: true
  632. variable_uuid: ba2692d2-fb11-11e7-9319-455b975e6257
  633. variable_session_id: 1
  634. variable_sip_gateway_name: 6f98ceb1-1ad0-478c-a524-69e6808b93b9
  635. variable_sip_invite_domain: sip.t38fax.com
  636. variable_sip_profile_name: gateway
  637. variable_video_media_flow: sendrecv
  638. variable_audio_media_flow: sendrecv
  639. variable_channel_name: sofia/external/12158258773
  640. variable_sip_destination_url: sip%3A12158258773%40sip.t38fax.com%3A5080
  641. variable_for_fax: 1
  642. variable_accountcode: 52.90.40.222
  643. variable_sip_h_X-accountcode: 52.90.40.222
  644. variable_domain_uuid: 76966341-7249-4fc3-98db-c64af81bc376
  645. variable_domain_name: 52.90.40.222
  646. variable_fax_file: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  647. variable_fax_enable_t38: true
  648. variable_fax_enable_t38_request: true
  649. variable_ignore_early_media: true
  650. variable_fax_uri: sofia/gateway/6f98ceb1-1ad0-478c-a524-69e6808b93b9/12158258773
  651. variable_fax_retry_attempts: 1
  652. variable_fax_retry_limit: 20
  653. variable_fax_retry_sleep: 180
  654. variable_fax_verbose: true
  655. variable_fax_use_ecm: off
  656. variable_api_hangup_hook: lua%20fax_retry.lua
  657. variable_originate_early_media: false
  658. variable_sip_outgoing_contact_uri: %3Csip%3Agw%2B6f98ceb1-1ad0-478c-a524-69e6808b93b9%4052.90.40.222%3A5080%3Btransport%3Dudp%3Bgw%3D6f98ceb1-1ad0-478c-a524-69e6808b93b9%3E
  659. variable_sip_req_uri: 12158258773%40sip.t38fax.com%3A5080
  660. variable_sofia_profile_name: external
  661. variable_recovery_profile_name: external
  662. variable_sip_local_network_addr: 52.90.40.222
  663. variable_sip_reply_host: 8.34.182.111
  664. variable_sip_reply_port: 5080
  665. variable_sip_network_ip: 8.34.182.111
  666. variable_sip_network_port: 5080
  667. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  668. variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20INFO,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20UPDATE
  669. variable_sip_invite_route_uri: %3Csip%3A8.34.182.111%3A5080%3Btransport%3Dudp%3Blr%3Bdrr%3E,%3Csip%3A8.34.182.111%3A5060%3Btransport%3Dudp%3Blr%3Bdrr%3E
  670. variable_sip_invite_record_route: %3Csip%3A8.34.182.111%3A5060%3Btransport%3Dudp%3Blr%3Bdrr%3E,%3Csip%3A8.34.182.111%3A5080%3Btransport%3Dudp%3Blr%3Bdrr%3E
  671. variable_sip_from_user: 0000000000
  672. variable_sip_from_uri: 0000000000%40sip.t38fax.com
  673. variable_sip_from_host: sip.t38fax.com
  674. variable_sip_to_user: 12158258773
  675. variable_sip_to_port: 5080
  676. variable_sip_to_uri: 12158258773%40sip.t38fax.com%3A5080
  677. variable_sip_to_host: sip.t38fax.com
  678. variable_sip_contact_user: nobody
  679. variable_sip_contact_port: 5061
  680. variable_sip_contact_uri: nobody%408.34.182.111%3A5061
  681. variable_sip_contact_host: 8.34.182.111
  682. variable_rtp_audio_recv_pt: 0
  683. variable_rtp_use_codec_name: PCMU
  684. variable_rtp_use_codec_rate: 8000
  685. variable_rtp_use_codec_ptime: 20
  686. variable_rtp_use_codec_channels: 1
  687. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
  688. variable_original_read_codec: PCMU
  689. variable_original_read_rate: 8000
  690. variable_write_codec: PCMU
  691. variable_write_rate: 8000
  692. variable_dtmf_type: rfc2833
  693. variable_local_media_ip: 172.31.55.240
  694. variable_local_media_port: 18078
  695. variable_advertised_media_ip: 52.90.40.222
  696. variable_rtp_use_timer_name: soft
  697. variable_rtp_use_pt: 0
  698. variable_rtp_use_ssrc: 1852424801
  699. variable_rtp_2833_send_payload: 101
  700. variable_rtp_2833_recv_payload: 101
  701. variable_remote_media_ip: 8.34.182.100
  702. variable_remote_media_port: 21516
  703. variable_endpoint_disposition: ANSWER
  704. variable_pre_transfer_caller_id_number: 0000000000
  705. variable_call_uuid: ba2692d2-fb11-11e7-9319-455b975e6257
  706. variable_current_application_data: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  707. variable_current_application: txfax
  708. variable_switch_r_sdp: v%3D0%0D%0Ao%3DSippy%203369124320713125429%202%20IN%20IP4%208.34.182.111%0D%0As%3DiFAX%0D%0At%3D0%200%0D%0Am%3Dimage%2021516%20udptl%20t38%0D%0Ac%3DIN%20IP4%208.34.182.100%0D%0Aa%3DT38FaxVersion%3A0%0D%0Aa%3DT38MaxBitRate%3A14400%0D%0Aa%3DT38FaxFillBitRemoval%0D%0Aa%3DT38FaxRateManagement%3AtransferredTCF%0D%0Aa%3DT38FaxMaxBuffer%3A2000%0D%0Aa%3DT38FaxMaxDatagram%3A400%0D%0Aa%3DT38FaxUdpEC%3At38UDPRedundancy%0D%0A
  709. variable_rtp_use_codec_string: PCMU,PCMA,GSM
  710. variable_has_t38: true
  711. variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201516125843%201516125845%20IN%20IP4%2052.90.40.222%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2052.90.40.222%0D%0At%3D0%200%0D%0Am%3Dimage%2018078%20udptl%20t38%0D%0Aa%3DT38FaxVersion%3A0%0D%0Aa%3DT38MaxBitRate%3A14400%0D%0Aa%3DT38FaxFillBitRemoval%0D%0Aa%3DT38FaxRateManagement%3AtransferredTCF%0D%0Aa%3DT38FaxMaxBuffer%3A2000%0D%0Aa%3DT38FaxMaxDatagram%3A400%0D%0Aa%3DT38FaxUdpEC%3At38UDPRedundancy%0D%0A
  712. variable_fax_v17_disabled: 0
  713. variable_fax_ecm_requested: 0
  714. variable_fax_filename: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  715. variable_sip_to_tag: HZXUvmZ2t83gH
  716. variable_sip_from_tag: 5hztxl73e4pjapwj.i
  717. variable_sip_cseq: 580
  718. variable_sip_call_id: 91855555-75b4-1236-4ea9-12266285acf2
  719. variable_sip_recover_contact: %3Csip%3A8.34.182.111%3A5061%3E
  720. variable_sip_full_via: SIP/2.0/UDP%208.34.182.111%3A5080%3Bbranch%3Dz9hG4bK-524287-1---432f9c22e291825f%3Brport%3D5080,SIP/2.0/UDP%208.34.182.111%3A5061%3Brport%3D5061%3Bbranch%3Dz9hG4bK-wg7zipzy7vbj6qva
  721. variable_sip_recover_via: SIP/2.0/UDP%208.34.182.111%3A5080%3Bbranch%3Dz9hG4bK-524287-1---432f9c22e291825f%3Brport%3D5080,SIP/2.0/UDP%208.34.182.111%3A5061%3Brport%3D5061%3Bbranch%3Dz9hG4bK-wg7zipzy7vbj6qva
  722. variable_sip_full_from: %3Csip%3A12158258773%40sip.t38fax.com%3A5080%3E%3Btag%3D5hztxl73e4pjapwj.i
  723. variable_sip_full_to: %3Csip%3A0000000000%40sip.t38fax.com%3E%3Btag%3DHZXUvmZ2t83gH
  724. variable_fax_file_image_resolution: 8031x3858
  725. variable_fax_image_pixel_size: 1728x1094
  726. variable_fax_file_image_pixel_size: 1728x1078
  727. variable_fax_longest_bad_row_run: 0
  728. variable_fax_encoding: 4
  729. variable_fax_encoding_name: T.4%202-D
  730. variable_sip_term_status: 200
  731. variable_proto_specific_hangup_cause: sip%3A200
  732. variable_sip_term_cause: 16
  733. variable_sip_user_agent: Sippy
  734. variable_sip_hangup_disposition: recv_bye
  735. variable_fax_success: 1
  736. variable_fax_result_code: 0
  737. variable_fax_result_text: OK
  738. variable_fax_ecm_used: off
  739. variable_fax_local_station_id: SpanDSP%20Fax%20Ident
  740. variable_fax_remote_station_id: iFAXSolutions
  741. variable_fax_document_transferred_pages: 1
  742. variable_fax_document_total_pages: 1
  743. variable_fax_image_resolution: 8040x3850
  744. variable_fax_image_size: 31089
  745. variable_fax_bad_rows: 0
  746. variable_fax_transfer_rate: 14400
  747. variable_read_codec: PCMU
  748. variable_read_rate: 8000
  749. variable_hangup_cause: NORMAL_CLEARING
  750. variable_hangup_cause_q850: 16
  751. variable_digits_dialed: none
  752. variable_start_stamp: 2018-01-16%2023%3A05%3A21
  753. variable_profile_start_stamp: 2018-01-16%2023%3A05%3A21
  754. variable_answer_stamp: 2018-01-16%2023%3A05%3A21
  755. variable_progress_stamp: 2018-01-16%2023%3A05%3A21
  756. variable_end_stamp: 2018-01-16%2023%3A05%3A57
  757. variable_start_epoch: 1516143921
  758. variable_start_uepoch: 1516143921416930
  759. variable_profile_start_epoch: 1516143921
  760. variable_profile_start_uepoch: 1516143921416930
  761. variable_answer_epoch: 1516143921
  762. variable_answer_uepoch: 1516143921816928
  763. variable_bridge_epoch: 0
  764. variable_bridge_uepoch: 0
  765. variable_last_hold_epoch: 0
  766. variable_last_hold_uepoch: 0
  767. variable_hold_accum_seconds: 0
  768. variable_hold_accum_usec: 0
  769. variable_hold_accum_ms: 0
  770. variable_resurrect_epoch: 0
  771. variable_resurrect_uepoch: 0
  772. variable_progress_epoch: 1516143921
  773. variable_progress_uepoch: 1516143921736879
  774. variable_progress_media_epoch: 0
  775. variable_progress_media_uepoch: 0
  776. variable_end_epoch: 1516143957
  777. variable_end_uepoch: 1516143957836793
  778. variable_last_app: txfax
  779. variable_last_arg: /usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif
  780. variable_caller_id: %22Outbound%20Call%22%20%3C12158258773%3E
  781. variable_duration: 36
  782. variable_billsec: 36
  783. variable_progresssec: 0
  784. variable_answersec: 0
  785. variable_waitsec: 0
  786. variable_progress_mediasec: 0
  787. variable_flow_billsec: 36
  788. variable_mduration: 36420
  789. variable_billmsec: 36020
  790. variable_progressmsec: 320
  791. variable_answermsec: 400
  792. variable_waitmsec: 0
  793. variable_progress_mediamsec: 0
  794. variable_flow_billmsec: 36420
  795. variable_uduration: 36419863
  796. variable_billusec: 36019865
  797. variable_progressusec: 319949
  798. variable_answerusec: 399998
  799. variable_waitusec: 0
  800. variable_progress_mediausec: 0
  801. variable_flow_billusec: 36419863
  802. variable_rtp_audio_in_raw_bytes: 22984
  803. variable_rtp_audio_in_media_bytes: 21608
  804. variable_rtp_audio_in_packet_count: 203
  805. variable_rtp_audio_in_media_packet_count: 195
  806. variable_rtp_audio_in_skip_packet_count: 14
  807. variable_rtp_audio_in_jitter_packet_count: 0
  808. variable_rtp_audio_in_dtmf_packet_count: 0
  809. variable_rtp_audio_in_cng_packet_count: 0
  810. variable_rtp_audio_in_flush_packet_count: 8
  811. variable_rtp_audio_in_largest_jb_size: 0
  812. variable_rtp_audio_in_jitter_min_variance: 20.09
  813. variable_rtp_audio_in_jitter_max_variance: 80.36
  814. variable_rtp_audio_in_jitter_loss_rate: 0.00
  815. variable_rtp_audio_in_jitter_burst_rate: 0.00
  816. variable_rtp_audio_in_mean_interval: 20.20
  817. variable_rtp_audio_in_flaw_total: 0
  818. variable_rtp_audio_in_quality_percentage: 100.00
  819. variable_rtp_audio_in_mos: 4.50
  820. variable_rtp_audio_out_raw_bytes: 175535
  821. variable_rtp_audio_out_media_bytes: 175535
  822. variable_rtp_audio_out_packet_count: 818
  823. variable_rtp_audio_out_media_packet_count: 818
  824. variable_rtp_audio_out_skip_packet_count: 0
  825. variable_rtp_audio_out_dtmf_packet_count: 0
  826. variable_rtp_audio_out_cng_packet_count: 0
  827. variable_rtp_audio_rtcp_packet_count: 0
  828. variable_rtp_audio_rtcp_octet_count: 0
  829. API-Command: lua
  830. API-Command-Argument: fax_retry.lua
  831.  
  832. '
  833. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] Success: '1'
  834. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] fax_result_text: 'OK'
  835. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] fax_file: '/usr/local/freeswitch/storage/fax/52.90.40.222/9000/temp/9bff0125-376f-4c8d-a63f-b245339bbbb6.tif'
  836. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] uuid: 'ba2692d2-fb11-11e7-9319-455b975e6257'
  837. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] fax_retry_attempts: 1
  838. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] fax_retry_limit: 20
  839. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] fax_retry_sleep: 30
  840. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] fax_uri: 'sofia/gateway/6f98ceb1-1ad0-478c-a524-69e6808b93b9/12158258773'
  841. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] accountcode: '52.90.40.222'
  842. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] origination_caller_id_name: 000000000000000
  843. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] origination_caller_id_number: 000000000000000
  844. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] fax_result_code: 0
  845. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] mailfrom_address:
  846. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] mailto_address:
  847. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] hangup_cause_q850: '16'
  848. 2018-01-16 23:05:58.436913 [INFO] switch_cpp.cpp:1365 [FAX] RETRY STATS SUCCESS: GATEWAY[sofia/gateway/6f98ceb1-1ad0-478c-a524-69e6808b93b9/12158258773] VARS[fax_use_ecm=true,fax_enable_t38=true,fax_enable_t38_request=true,fax_disable_v17=false]
  849. 2018-01-16 23:05:58.436913 [WARNING] switch_utils.c:1195 Failed to delete file [in_uuid);
  850.  --result = array['domain']['template']['name'];
  851. --freeswitch.consoleLog("notice", result .. "\n");
  852.  --for i, ext in ipairs(array.fax.alloy▒M]
  853. 2018-01-16 23:05:58.436913 [ERR] switch_utils.c:1211 EMAIL NOT SENT, error [No to address specified]
  854. 2018-01-16 23:05:58.436913 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f02101344d0 released.
  855. 2018-01-16 23:05:58.436913 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(fax_retry.lua):
  856.  
  857. 2018-01-16 23:05:58.436913 [DEBUG] switch_core_state_machine.c:619 (sofia/external/12158258773) State Change CS_HANGUP -> CS_REPORTING
  858. 2018-01-16 23:05:58.436913 [DEBUG] switch_core_state_machine.c:584 (sofia/external/12158258773) Running State Change CS_REPORTING (Cur 1 Tot 1)
  859. 2018-01-16 23:05:58.436913 [DEBUG] switch_core_state_machine.c:938 (sofia/external/12158258773) State REPORTING
  860. 2018-01-16 23:05:58.436913 [ALERT] switch_core_state_machine.c:938 sofia/external/12158258773 Send KeyFrame
  861. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_state_machine.c:174 sofia/external/12158258773 Standard REPORTING, cause: NORMAL_CLEARING
  862. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_state_machine.c:938 (sofia/external/12158258773) State REPORTING going to sleep
  863. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_state_machine.c:610 (sofia/external/12158258773) State Change CS_REPORTING -> CS_DESTROY
  864. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_session.c:1665 Session 1 (sofia/external/12158258773) Locked, Waiting on external entities
  865. 2018-01-16 23:05:58.476923 [NOTICE] switch_core_session.c:1683 Session 1 (sofia/external/12158258773) Ended
  866. 2018-01-16 23:05:58.476923 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/12158258773 [CS_DESTROY]
  867. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_state_machine.c:741 (sofia/external/12158258773) Running State Change CS_DESTROY (Cur 0 Tot 1)
  868. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_state_machine.c:751 (sofia/external/12158258773) State DESTROY
  869. 2018-01-16 23:05:58.476923 [ALERT] switch_core_state_machine.c:751 sofia/external/12158258773 Send KeyFrame
  870. 2018-01-16 23:05:58.476923 [DEBUG] mod_sofia.c:343 sofia/external/12158258773 SOFIA DESTROY
  871. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_state_machine.c:181 sofia/external/12158258773 Standard DESTROY
  872. 2018-01-16 23:05:58.476923 [DEBUG] switch_core_state_machine.c:751 (sofia/external/12158258773) State DESTROY going to sleep
  873. 2018-01-16 23:06:00.896865 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  874. 2018-01-16 23:06:10.916846 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  875. 2018-01-16 23:06:20.916880 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  876. 2018-01-16 23:06:30.916807 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  877. 2018-01-16 23:06:40.916861 [ALERT] mod_rtmp.c:941 RTMP Garbage Collection
  878.  

Reply to "Fax ECM Errors"