From new, 3 Years ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and Untitled
  1. freeswitch@pbx2.conexo.tech>
  2. 2021-02-19 08:15:22.932813 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
  3. 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]
  4. 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
  5. 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)
  6. 2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:628 (sofia/default/01119112588@212.97.34.174) State INIT
  7. 2021-02-19 08:15:22.932813 [DEBUG] mod_sofia.c:93 sofia/default/01119112588@212.97.34.174 SOFIA INIT
  8. 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
  9. Local SDP:
  10. v=0
  11. o=FreeSWITCH 1613700074 1613700075 IN IP4 212.97.34.173
  12. s=FreeSWITCH
  13. c=IN IP4 212.97.34.173
  14. t=0 0
  15. m=audio 18848 RTP/AVP 8 101
  16. a=rtpmap:8 PCMA/8000
  17. a=rtpmap:101 telephone-event/8000
  18. a=fmtp:101 0-16
  19. a=ptime:20
  20. a=sendrecv
  21.  
  22. 2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:40 sofia/default/01119112588@212.97.34.174 Standard INIT
  23. 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
  24. 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
  25. 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)
  26. 2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:644 (sofia/default/01119112588@212.97.34.174) State ROUTING
  27. 2021-02-19 08:15:22.932813 [DEBUG] mod_sofia.c:154 sofia/default/01119112588@212.97.34.174 SOFIA ROUTING
  28. 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
  29. 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
  30. 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)
  31. 2021-02-19 08:15:22.932813 [DEBUG] switch_core_state_machine.c:663 (sofia/default/01119112588@212.97.34.174) State CONSUME_MEDIA
  32. 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
  33. 2021-02-19 08:15:22.932813 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [calling][0]
  34. 2021-02-19 08:15:22.952805 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
  35. 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]
  36. 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
  37. 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)
  38. 2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:628 (sofia/default/01119112588@212.97.34.174) State INIT
  39. 2021-02-19 08:15:22.952805 [DEBUG] mod_sofia.c:93 sofia/default/01119112588@212.97.34.174 SOFIA INIT
  40. 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
  41. Local SDP:
  42. v=0
  43. o=FreeSWITCH 1613698778 1613698779 IN IP4 212.97.34.173
  44. s=FreeSWITCH
  45. c=IN IP4 212.97.34.173
  46. t=0 0
  47. m=audio 20144 RTP/AVP 8 101
  48. a=rtpmap:8 PCMA/8000
  49. a=rtpmap:101 telephone-event/8000
  50. a=fmtp:101 0-16
  51. a=ptime:20
  52. a=sendrecv
  53.  
  54. 2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:40 sofia/default/01119112588@212.97.34.174 Standard INIT
  55. 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
  56. 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
  57. 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)
  58. 2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:644 (sofia/default/01119112588@212.97.34.174) State ROUTING
  59. 2021-02-19 08:15:22.952805 [DEBUG] mod_sofia.c:154 sofia/default/01119112588@212.97.34.174 SOFIA ROUTING
  60. 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
  61. 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
  62. 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)
  63. 2021-02-19 08:15:22.952805 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [calling][0]
  64. 2021-02-19 08:15:22.952805 [DEBUG] switch_core_state_machine.c:663 (sofia/default/01119112588@212.97.34.174) State CONSUME_MEDIA
  65. 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
  66. 2021-02-19 08:15:23.012808 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [proceeding][183]
  67. 2021-02-19 08:15:23.012808 [DEBUG] sofia.c:7336 Remote SDP:
  68. v=0
  69. o=FreeSWITCH 1613700675 1613700676 IN IP4 212.97.34.172
  70. s=FreeSWITCH
  71. c=IN IP4 212.97.34.172
  72. t=0 0
  73. m=audio 18248 RTP/AVP 8 101
  74. a=rtpmap:8 PCMA/8000
  75. a=rtpmap:101 telephone-event/8000
  76. a=fmtp:101 0-16
  77. a=ptime:20
  78.  
  79. 2021-02-19 08:15:23.012808 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/01119112588@212.97.34.174!
  80. 2021-02-19 08:15:23.012808 [DEBUG] switch_channel.c:3565 (sofia/default/01119112588@212.97.34.174) Callstate Change DOWN -> EARLY
  81. 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]
  82. 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
  83. 2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  84. 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
  85. 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
  86. 2021-02-19 08:15:23.012808 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  87. 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
  88. 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
  89. 2021-02-19 08:15:23.012808 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  90. 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
  91. 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
  92. 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
  93. 2021-02-19 08:15:23.032808 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [proceeding][183]
  94. 2021-02-19 08:15:23.032808 [DEBUG] sofia.c:7336 Remote SDP:
  95. v=0
  96. o=FreeSWITCH 1613687817 1613687818 IN IP4 212.97.34.172
  97. s=FreeSWITCH
  98. c=IN IP4 212.97.34.172
  99. t=0 0
  100. m=audio 31106 RTP/AVP 8 101
  101. a=rtpmap:8 PCMA/8000
  102. a=rtpmap:101 telephone-event/8000
  103. a=fmtp:101 0-16
  104. a=ptime:20
  105.  
  106. 2021-02-19 08:15:23.032808 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/01119112588@212.97.34.174!
  107. 2021-02-19 08:15:23.032808 [DEBUG] switch_channel.c:3565 (sofia/default/01119112588@212.97.34.174) Callstate Change DOWN -> EARLY
  108. 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]
  109. 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
  110. 2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  111. 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
  112. 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
  113. 2021-02-19 08:15:23.032808 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  114. 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
  115. 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
  116. 2021-02-19 08:15:23.032808 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  117. 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
  118. 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
  119. 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
  120. 2021-02-19 08:15:25.992824 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completing][200]
  121. 2021-02-19 08:15:25.992824 [DEBUG] sofia.c:7333 Duplicate SDP
  122. v=0
  123. o=FreeSWITCH 1613700675 1613700676 IN IP4 212.97.34.172
  124. s=FreeSWITCH
  125. c=IN IP4 212.97.34.172
  126. t=0 0
  127. m=audio 18248 RTP/AVP 8 101
  128. a=rtpmap:8 PCMA/8000
  129. a=rtpmap:101 telephone-event/8000
  130. a=fmtp:101 0-16
  131. a=ptime:20
  132.  
  133. 2021-02-19 08:15:25.992824 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
  134. 2021-02-19 08:15:25.992824 [NOTICE] sofia.c:8445 Channel [sofia/default/01119112588@212.97.34.174] has been answered
  135. 2021-02-19 08:15:25.992824 [DEBUG] switch_channel.c:3865 (sofia/default/01119112588@212.97.34.174) Callstate Change EARLY -> ACTIVE
  136. 2021-02-19 08:15:26.012819 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/01119112588@212.97.34.174]
  137. 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>
  138. 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
  139. 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)
  140. 2021-02-19 08:15:26.012819 [DEBUG] switch_core_state_machine.c:651 (sofia/default/01119112588@212.97.34.174) State EXECUTE
  141. 2021-02-19 08:15:26.012819 [DEBUG] mod_sofia.c:209 sofia/default/01119112588@212.97.34.174 SOFIA EXECUTE
  142. 2021-02-19 08:15:26.012819 [DEBUG] switch_core_state_machine.c:329 sofia/default/01119112588@212.97.34.174 Standard EXECUTE
  143. EXECUTE [depth=0] sofia/default/01119112588@212.97.34.174 txfax(/opt/ASTPP/web_interface/astpp/FAX/send_fax_files/20210219071442.tiff)
  144. 2021-02-19 08:15:26.012819 [DEBUG] mod_spandsp_fax.c:1514 Raw read codec activation Success L16 20000
  145. 2021-02-19 08:15:26.012819 [DEBUG] switch_core_codec.c:223 sofia/default/01119112588@212.97.34.174 Push codec L16:100
  146. 2021-02-19 08:15:26.012819 [DEBUG] mod_spandsp_fax.c:1532 Raw write codec activation Success L16
  147. 2021-02-19 08:15:26.052828 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  148. 2021-02-19 08:15:26.112823 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completing][200]
  149. 2021-02-19 08:15:26.112823 [DEBUG] sofia.c:7333 Duplicate SDP
  150. v=0
  151. o=FreeSWITCH 1613687817 1613687818 IN IP4 212.97.34.172
  152. s=FreeSWITCH
  153. c=IN IP4 212.97.34.172
  154. t=0 0
  155. m=audio 31106 RTP/AVP 8 101
  156. a=rtpmap:8 PCMA/8000
  157. a=rtpmap:101 telephone-event/8000
  158. a=fmtp:101 0-16
  159. a=ptime:20
  160.  
  161. 2021-02-19 08:15:26.112823 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
  162. 2021-02-19 08:15:26.112823 [NOTICE] sofia.c:8445 Channel [sofia/default/01119112588@212.97.34.174] has been answered
  163. 2021-02-19 08:15:26.112823 [DEBUG] switch_channel.c:3865 (sofia/default/01119112588@212.97.34.174) Callstate Change EARLY -> ACTIVE
  164. 2021-02-19 08:15:26.132827 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/01119112588@212.97.34.174]
  165. 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>
  166. 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
  167. 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)
  168. 2021-02-19 08:15:26.132827 [DEBUG] switch_core_state_machine.c:651 (sofia/default/01119112588@212.97.34.174) State EXECUTE
  169. 2021-02-19 08:15:26.132827 [DEBUG] mod_sofia.c:209 sofia/default/01119112588@212.97.34.174 SOFIA EXECUTE
  170. 2021-02-19 08:15:26.132827 [DEBUG] switch_core_state_machine.c:329 sofia/default/01119112588@212.97.34.174 Standard EXECUTE
  171. EXECUTE [depth=0] sofia/default/01119112588@212.97.34.174 txfax(/opt/ASTPP/web_interface/astpp/FAX/send_fax_files/20210219071442.tiff)
  172. 2021-02-19 08:15:26.132827 [DEBUG] mod_spandsp_fax.c:1514 Raw read codec activation Success L16 20000
  173. 2021-02-19 08:15:26.132827 [DEBUG] switch_core_codec.c:223 sofia/default/01119112588@212.97.34.174 Push codec L16:100
  174. 2021-02-19 08:15:26.132827 [DEBUG] mod_spandsp_fax.c:1532 Raw write codec activation Success L16
  175. 2021-02-19 08:15:26.172812 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  176. 2021-02-19 08:15:26.852829 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [received][100]
  177. 2021-02-19 08:15:26.852829 [DEBUG] sofia.c:7336 Remote SDP:
  178. v=0
  179. o=FreeSWITCH 1613687817 1613687820 IN IP4 212.97.34.172
  180. s=FreeSWITCH
  181. c=IN IP4 212.97.34.172
  182. t=0 0
  183. m=image 31106 udptl t38
  184. a=T38FaxVersion:0
  185. a=T38MaxBitRate:14400
  186. a=T38FaxRateManagement:transferredTCF
  187. a=T38FaxMaxBuffer:500
  188. a=T38FaxMaxDatagram:500
  189. a=T38FaxUdpEC:t38UDPRedundancy
  190.  
  191. 2021-02-19 08:15:26.852829 [DEBUG] switch_core_media.c:5185 sofia/default/01119112588@212.97.34.174 T38 ACCEPT on request
  192. 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
  193. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1095 T38 SDP Origin = FreeSWITCH
  194. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1096 T38FaxVersion = 0
  195. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1097 T38MaxBitRate = 14400
  196. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1098 T38FaxFillBitRemoval = 0
  197. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1099 T38FaxTranscodingMMR = 0
  198. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1100 T38FaxTranscodingJBIG = 0
  199. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1101 T38FaxRateManagement = 'transferredTCF'
  200. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1102 T38FaxMaxBuffer = 500
  201. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1103 T38FaxMaxDatagram = 500
  202. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1104 T38FaxUdpEC = 't38UDPRedundancy'
  203. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1105 T38VendorInfo = ''
  204. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1106 ip = '212.97.34.172'
  205. 2021-02-19 08:15:26.872822 [DEBUG] mod_spandsp_fax.c:1108 port = 31106
  206. 2021-02-19 08:15:26.872822 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:31106] has not changed.
  207. 2021-02-19 08:15:26.872822 [DEBUG] switch_core_media.c:11914 sofia/default/01119112588@212.97.34.174 image media sdp:
  208. v=0
  209. o=FreeSWITCH 1613700074 1613700076 IN IP4 212.97.34.173
  210. s=FreeSWITCH
  211. c=IN IP4 212.97.34.173
  212. t=0 0
  213. m=image 18848 udptl t38
  214. a=T38FaxVersion:0
  215. a=T38MaxBitRate:14400
  216. a=T38FaxRateManagement:transferredTCF
  217. a=T38FaxMaxBuffer:500
  218. a=T38FaxMaxDatagram:400
  219. a=T38FaxUdpEC:t38UDPRedundancy
  220.  
  221. 2021-02-19 08:15:26.872822 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:31106] has not changed.
  222. 2021-02-19 08:15:26.872822 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completed][200]
  223. 2021-02-19 08:15:26.892817 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
  224. 2021-02-19 08:15:28.512808 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [received][100]
  225. 2021-02-19 08:15:28.512808 [DEBUG] sofia.c:7336 Remote SDP:
  226. v=0
  227. o=FreeSWITCH 1613700675 1613700678 IN IP4 212.97.34.172
  228. s=FreeSWITCH
  229. c=IN IP4 212.97.34.172
  230. t=0 0
  231. m=image 18248 udptl t38
  232. a=T38FaxVersion:0
  233. a=T38MaxBitRate:14400
  234. a=T38FaxRateManagement:transferredTCF
  235. a=T38FaxMaxBuffer:500
  236. a=T38FaxMaxDatagram:500
  237. a=T38FaxUdpEC:t38UDPRedundancy
  238.  
  239. 2021-02-19 08:15:28.512808 [DEBUG] switch_core_media.c:5185 sofia/default/01119112588@212.97.34.174 T38 ACCEPT on request
  240. 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
  241. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1095 T38 SDP Origin = FreeSWITCH
  242. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1096 T38FaxVersion = 0
  243. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1097 T38MaxBitRate = 14400
  244. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1098 T38FaxFillBitRemoval = 0
  245. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1099 T38FaxTranscodingMMR = 0
  246. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1100 T38FaxTranscodingJBIG = 0
  247. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1101 T38FaxRateManagement = 'transferredTCF'
  248. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1102 T38FaxMaxBuffer = 500
  249. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1103 T38FaxMaxDatagram = 500
  250. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1104 T38FaxUdpEC = 't38UDPRedundancy'
  251. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1105 T38VendorInfo = ''
  252. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1106 ip = '212.97.34.172'
  253. 2021-02-19 08:15:28.532811 [DEBUG] mod_spandsp_fax.c:1108 port = 18248
  254. 2021-02-19 08:15:28.532811 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:18248] has not changed.
  255. 2021-02-19 08:15:28.532811 [DEBUG] switch_core_media.c:11914 sofia/default/01119112588@212.97.34.174 image media sdp:
  256. v=0
  257. o=FreeSWITCH 1613698778 1613698780 IN IP4 212.97.34.173
  258. s=FreeSWITCH
  259. c=IN IP4 212.97.34.173
  260. t=0 0
  261. m=image 20144 udptl t38
  262. a=T38FaxVersion:0
  263. a=T38MaxBitRate:14400
  264. a=T38FaxRateManagement:transferredTCF
  265. a=T38FaxMaxBuffer:500
  266. a=T38FaxMaxDatagram:400
  267. a=T38FaxUdpEC:t38UDPRedundancy
  268.  
  269. 2021-02-19 08:15:28.532811 [DEBUG] switch_core_media.c:12343 Remote address:port [212.97.34.172:18248] has not changed.
  270. 2021-02-19 08:15:28.532811 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [completed][200]
  271. 2021-02-19 08:15:28.552810 [DEBUG] sofia.c:7326 Channel sofia/default/01119112588@212.97.34.174 entering state [ready][200]
  272. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:346 === Negotiation Result =======================================================
  273. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:347 Remote station id: 01119112588        
  274. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:348 Local station id:  509
  275. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:349 Transfer Rate:     14400
  276. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:350 ECM status         off
  277. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:351 T38 status         negotiated
  278. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:352 remote country:    
  279. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:353 remote vendor:    
  280. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:354 remote model:      
  281. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:356 Total fax pages:   0
  282. 2021-02-19 08:15:33.492812 [DEBUG] mod_spandsp_fax.c:359 ==============================================================================
  283. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:346 === Negotiation Result =======================================================
  284. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:347 Remote station id: 01119112588        
  285. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:348 Local station id:  509
  286. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:349 Transfer Rate:     14400
  287. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:350 ECM status         off
  288. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:351 T38 status         negotiated
  289. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:352 remote country:    
  290. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:353 remote vendor:    
  291. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:354 remote model:      
  292. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:356 Total fax pages:   0
  293. 2021-02-19 08:15:35.172816 [DEBUG] mod_spandsp_fax.c:359 ==============================================================================
  294. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:471 ==== Page Sent ===============================================================
  295. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:472 Page no = 1
  296. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:474 Total fax pages:   1
  297. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:476 Image type = bi-level (bi-level in the file)
  298. 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)
  299. 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)
  300. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:479 Compression = T.4 2-D (4)
  301. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:480 Compressed image size = 3376 bytes
  302. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:481 Bad rows = 0
  303. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:482 Longest bad row run = 0
  304. 2021-02-19 08:15:47.252810 [DEBUG] mod_spandsp_fax.c:483 ==============================================================================
  305. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:471 ==== Page Sent ===============================================================
  306. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:472 Page no = 1
  307. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:474 Total fax pages:   1
  308. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:476 Image type = bi-level (bi-level in the file)
  309. 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)
  310. 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)
  311. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:479 Compression = T.4 2-D (4)
  312. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:480 Compressed image size = 3376 bytes
  313. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:481 Bad rows = 0
  314. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:482 Longest bad row run = 0
  315. 2021-02-19 08:15:48.932823 [DEBUG] mod_spandsp_fax.c:483 ==============================================================================
  316. 2021-02-19 08:15:49.192807 [NOTICE] sofia.c:1089 Hangup sofia/default/01119112588@212.97.34.174 [CS_EXECUTE] [NORMAL_CLEARING]
  317. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:545 ==============================================================================
  318. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:549 Fax successfully sent.
  319. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:562 Remote station id: 01119112588        
  320. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:563 Local station id:  509
  321. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:564 Pages transferred: 1
  322. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:566 Total fax pages:   1
  323. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:567 Image resolution:  8040x3850
  324. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:568 Transfer Rate:     14400
  325. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:570 ECM status         off
  326. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:571 T38 status         negotiated
  327. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:572 remote country:    
  328. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:573 remote vendor:    
  329. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:574 remote model:      
  330. 2021-02-19 08:15:49.192807 [DEBUG] mod_spandsp_fax.c:576 ==============================================================================
  331. 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.
  332. 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)
  333. 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
  334. 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)
  335. 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
  336. 2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:850 (sofia/default/01119112588@212.97.34.174) State HANGUP
  337. 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
  338. 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
  339. 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
  340. 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
  341. 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)
  342. 2021-02-19 08:15:49.192807 [DEBUG] switch_core_state_machine.c:936 (sofia/default/01119112588@212.97.34.174) State REPORTING
  343. 2021-02-19 08:15:49.192807 [INFO] mod_json_cdr.c:271 Process [ccf81626-93f6-423c-933e-abb7b879d62d.cdr.json]
  344. 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
  345. 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
  346. 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
  347. 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
  348. 2021-02-19 08:15:49.212808 [NOTICE] switch_core_session.c:1744 Session 58099 (sofia/default/01119112588@212.97.34.174) Ended
  349. 2021-02-19 08:15:49.212808 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/01119112588@212.97.34.174 [CS_DESTROY]
  350. 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)
  351. 2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:749 (sofia/default/01119112588@212.97.34.174) State DESTROY
  352. 2021-02-19 08:15:49.212808 [DEBUG] mod_sofia.c:364 sofia/default/01119112588@212.97.34.174 SOFIA DESTROY
  353. 2021-02-19 08:15:49.212808 [DEBUG] switch_core_state_machine.c:181 sofia/default/01119112588@212.97.34.174 Standard DESTROY
  354. 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
  355. 2021-02-19 08:15:50.872826 [NOTICE] sofia.c:1089 Hangup sofia/default/01119112588@212.97.34.174 [CS_EXECUTE] [NORMAL_CLEARING]
  356. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:545 ==============================================================================
  357. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:549 Fax successfully sent.
  358. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:562 Remote station id: 01119112588        
  359. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:563 Local station id:  509
  360. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:564 Pages transferred: 1
  361. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:566 Total fax pages:   1
  362. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:567 Image resolution:  8040x3850
  363. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:568 Transfer Rate:     14400
  364. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:570 ECM status         off
  365. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:571 T38 status         negotiated
  366. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:572 remote country:    
  367. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:573 remote vendor:    
  368. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:574 remote model:      
  369. 2021-02-19 08:15:50.872826 [DEBUG] mod_spandsp_fax.c:576 ==============================================================================
  370. 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.
  371. 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)
  372. 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
  373. 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)
  374. 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
  375. 2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:850 (sofia/default/01119112588@212.97.34.174) State HANGUP
  376. 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
  377. 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
  378. 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
  379. 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
  380. 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)
  381. 2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:936 (sofia/default/01119112588@212.97.34.174) State REPORTING
  382. 2021-02-19 08:15:50.872826 [INFO] mod_json_cdr.c:271 Process [212dc9f6-8efc-4fe6-8990-fa765ac332a2.cdr.json]
  383. 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
  384. 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
  385. 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
  386. 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
  387. 2021-02-19 08:15:50.872826 [NOTICE] switch_core_session.c:1744 Session 58100 (sofia/default/01119112588@212.97.34.174) Ended
  388. 2021-02-19 08:15:50.872826 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/01119112588@212.97.34.174 [CS_DESTROY]
  389. 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)
  390. 2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:749 (sofia/default/01119112588@212.97.34.174) State DESTROY
  391. 2021-02-19 08:15:50.872826 [DEBUG] mod_sofia.c:364 sofia/default/01119112588@212.97.34.174 SOFIA DESTROY
  392. 2021-02-19 08:15:50.872826 [DEBUG] switch_core_state_machine.c:181 sofia/default/01119112588@212.97.34.174 Standard DESTROY
  393. 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
  394.