From Sadegh Sadrzadeh, 7 Years ago, written in Plain Text.
- go back
Embed
Viewing differences between and fax on single freeswitch
  1. =========
  2. DIAL PLAN
  3. =========
  4. <context name="default_context">
  5.     <extension name="send_fax">
  6.         <condition field="destination_number" expression="SENDFAXEXT">
  7.             <action application="answer" />
  8.             <action application="log" data="ALERT inside sendfax destination: ${destination_number}" />
  9.             <action application="txfax" data="/tmp/tx.tiff" />
  10.             <action application="hangup" />
  11.         </condition>
  12.     </extension>
  13.     <extension name="recv_fax">
  14.         <condition field="destination_number" expression="RECVFAXEXT">
  15.             <action application="answer" />
  16.             <action application="log" data="ALERT inside recv_fax" />
  17.             <action application="rxfax" data="/tmp/rx.tiff" />
  18.             <action application="hangup" />
  19.         </condition>
  20.     </extension>
  21. </context>
  22.  
  23. ==================
  24. ORIGINATES I TRIED
  25. ==================
  26. originate {fax_verbose=true,ignore_early_media=true}sofia/internal/RECVFAXEXT@192.168.56.101 SENDFAXEXT XML default_context mycallername +123456
  27.  
  28.  
  29. originate {ignore_early_media=true,absolute_codec_string='PCMU,PCMA',fax_enable_t38=true,fax_verbose=true,fax_use_ecm=true,fax_enable_t38_request=true}sofia/internal/RECVFAXEXT@192.168.56.101 SENDFAXEXT XML default_context mycallername +123456
  30.  
  31.  
  32. originate {ignore_early_media=true,absolute_codec_string='PCMU,PCMA',fax_enable_t38=true,fax_verbose=true,fax_use_ecm=false,fax_enable_t38_request=false}sofia/internal/RECVFAXEXT@192.168.56.101 SENDFAXEXT XML default_context mycallername +123456
  33.  
  34.  
  35. ====================
  36. ORIGINATE RESULT LOG
  37. ====================
  38. +OK log level  [7]
  39. freeswitch@debian> originate {fax_verbose=true,ignore_early_media=true}sofia/internal/RECVFAXEXT@192.168.56.101 SENDFAXEXT XML default_context mycallername +123456
  40. +OK b82fdeb1-d954-4159-9778-7fde1ab67680
  41.  
  42. 2017-06-13 09:41:48.481892 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  43. 2017-06-13 09:41:48.481892 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/RECVFAXEXT@192.168.56.101 [b82fdeb1-d954-4159-9778-7fde1ab67680]
  44. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:4818 (sofia/internal/RECVFAXEXT@192.168.56.101) State Change CS_NEW -> CS_INIT
  45. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/RECVFAXEXT@192.168.56.101) Running State Change CS_INIT (Cur 1 Tot 1)
  46. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/RECVFAXEXT@192.168.56.101) State INIT
  47. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:90 sofia/internal/RECVFAXEXT@192.168.56.101 SOFIA INIT
  48. 2017-06-13 09:41:48.481892 [DEBUG] sofia_glue.c:1295 sofia/internal/RECVFAXEXT@192.168.56.101 sending invite version: 1.6.17 -34-0fc0946 64bit
  49. Local SDP:
  50. v=0
  51. o=FreeSWITCH 1497339796 1497339797 IN IP4 192.168.56.101
  52. s=FreeSWITCH
  53. c=IN IP4 192.168.56.101
  54. t=0 0
  55. m=audio 21512 RTP/AVP 0 8 101 13
  56. a=rtpmap:0 PCMU/8000
  57. a=rtpmap:8 PCMA/8000
  58. a=rtpmap:101 telephone-event/8000
  59. a=fmtp:101 0-16
  60. a=rtpmap:13 CN/8000
  61. a=ptime:20
  62. a=sendrecv
  63.  
  64. 2017-06-13 09:41:48.481892 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/+123456@192.168.56.101 [79f2c010-499c-41cf-bc2e-a8fbab1e558d]
  65. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:40 sofia/internal/RECVFAXEXT@192.168.56.101 Standard INIT
  66. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/RECVFAXEXT@192.168.56.101) State Change CS_INIT -> CS_ROUTING
  67. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/RECVFAXEXT@192.168.56.101) State INIT going to sleep
  68. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/RECVFAXEXT@192.168.56.101) Running State Change CS_ROUTING (Cur 2 Tot 2)
  69. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7048 Channel sofia/internal/RECVFAXEXT@192.168.56.101 entering state [calling][0]
  70. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/RECVFAXEXT@192.168.56.101) State ROUTING
  71. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:143 sofia/internal/RECVFAXEXT@192.168.56.101 SOFIA ROUTING
  72. 2017-06-13 09:41:48.481892 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/RECVFAXEXT@192.168.56.101) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  73. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/RECVFAXEXT@192.168.56.101) State ROUTING going to sleep
  74. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/RECVFAXEXT@192.168.56.101) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 2)
  75. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/RECVFAXEXT@192.168.56.101) State CONSUME_MEDIA
  76. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/RECVFAXEXT@192.168.56.101) State CONSUME_MEDIA going to sleep
  77. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+123456@192.168.56.101) Running State Change CS_NEW (Cur 2 Tot 2)
  78. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:9834 sofia/internal/+123456@192.168.56.101 receiving invite from 192.168.56.101:5060 version: 1.6.17 -34-0fc0946 64bit
  79. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7048 Channel sofia/internal/+123456@192.168.56.101 entering state [received][100]
  80. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7058 Remote SDP:
  81. v=0
  82. o=FreeSWITCH 1497339796 1497339797 IN IP4 192.168.56.101
  83. s=FreeSWITCH
  84. c=IN IP4 192.168.56.101
  85. t=0 0
  86. m=audio 21512 RTP/AVP 0 8 101 13
  87. a=rtpmap:0 PCMU/8000
  88. a=rtpmap:8 PCMA/8000
  89. a=rtpmap:101 telephone-event/8000
  90. a=fmtp:101 0-16
  91. a=rtpmap:13 CN/8000
  92. a=ptime:20
  93.  
  94. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  95. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  96. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  97. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  98. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  99. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  100. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 101@8000
  101. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/+123456@192.168.56.101 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  102. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_codec.c:111 sofia/internal/+123456@192.168.56.101 Original read codec set to PCMU:0
  103. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 101@8000
  104. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4754 sofia/internal/+123456@192.168.56.101 Set 2833 dtmf send payload to 101 recv payload to 101
  105. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7471 (sofia/internal/+123456@192.168.56.101) State Change CS_NEW -> CS_INIT
  106. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/+123456@192.168.56.101) State NEW
  107. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+123456@192.168.56.101) Running State Change CS_INIT (Cur 2 Tot 2)
  108. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/+123456@192.168.56.101) State INIT
  109. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:90 sofia/internal/+123456@192.168.56.101 SOFIA INIT
  110. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:40 sofia/internal/+123456@192.168.56.101 Standard INIT
  111. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/+123456@192.168.56.101) State Change CS_INIT -> CS_ROUTING
  112. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/+123456@192.168.56.101) State INIT going to sleep
  113. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+123456@192.168.56.101) Running State Change CS_ROUTING (Cur 2 Tot 2)
  114. 2017-06-13 09:41:48.481892 [DEBUG] switch_channel.c:2249 (sofia/internal/+123456@192.168.56.101) Callstate Change DOWN -> RINGING
  115. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/+123456@192.168.56.101) State ROUTING
  116. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:143 sofia/internal/+123456@192.168.56.101 SOFIA ROUTING
  117. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:236 sofia/internal/+123456@192.168.56.101 Standard ROUTING
  118. 2017-06-13 09:41:48.481892 [INFO] mod_dialplan_xml.c:637 Processing mycallername <+123456>->RECVFAXEXT in context default_context
  119. Dialplan: sofia/internal/+123456@192.168.56.101 parsing [default_context->send_fax] continue=false
  120. Dialplan: sofia/internal/+123456@192.168.56.101 Regex (FAIL) [send_fax] destination_number(RECVFAXEXT) =~ /SENDFAXEXT/ break=on-false
  121. Dialplan: sofia/internal/+123456@192.168.56.101 parsing [default_context->recv_fax] continue=false
  122. Dialplan: sofia/internal/+123456@192.168.56.101 Regex (PASS) [recv_fax] destination_number(RECVFAXEXT) =~ /RECVFAXEXT/ break=on-false
  123. Dialplan: sofia/internal/+123456@192.168.56.101 Action answer()
  124. Dialplan: sofia/internal/+123456@192.168.56.101 Action log(ALERT inside recv_fax)
  125. Dialplan: sofia/internal/+123456@192.168.56.101 Action rxfax(/tmp/rx.tiff)
  126. Dialplan: sofia/internal/+123456@192.168.56.101 Action hangup()
  127. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/+123456@192.168.56.101) State Change CS_ROUTING -> CS_EXECUTE
  128. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/+123456@192.168.56.101) State ROUTING going to sleep
  129. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+123456@192.168.56.101) Running State Change CS_EXECUTE (Cur 2 Tot 2)
  130. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/+123456@192.168.56.101) State EXECUTE
  131. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:198 sofia/internal/+123456@192.168.56.101 SOFIA EXECUTE
  132. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:328 sofia/internal/+123456@192.168.56.101 Standard EXECUTE
  133. EXECUTE sofia/internal/+123456@192.168.56.101 answer()
  134. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/+123456@192.168.56.101] 192.168.56.101 port 31130 -> 192.168.56.101 port 21512 codec: 0 ms: 20
  135. 2017-06-13 09:41:48.481892 [DEBUG] switch_rtp.c:4114 Not using a timer
  136. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7166 sofia/internal/+123456@192.168.56.101 Set 2833 dtmf send payload to 101
  137. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7173 sofia/internal/+123456@192.168.56.101 Set 2833 dtmf receive payload to 101
  138. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7196 sofia/internal/+123456@192.168.56.101 Set rtp dtmf delay to 40
  139. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7202 Set comfort noise payload to 13
  140. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/+123456@192.168.56.101:
  141. v=0
  142. o=FreeSWITCH 1497330178 1497330179 IN IP4 192.168.56.101
  143. s=FreeSWITCH
  144. c=IN IP4 192.168.56.101
  145. t=0 0
  146. m=audio 31130 RTP/AVP 0 101 13
  147. a=rtpmap:0 PCMU/8000
  148. a=rtpmap:101 telephone-event/8000
  149. a=fmtp:101 0-16
  150. a=rtpmap:13 CN/8000
  151. a=ptime:20
  152. a=sendrecv
  153.  
  154. 2017-06-13 09:41:48.481892 [INFO] sofia.c:1279 sofia/internal/RECVFAXEXT@192.168.56.101 Update Callee ID to "RECVFAXEXT" <sip:RECVFAXEXT@192.168.56.101>
  155. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7048 Channel sofia/internal/RECVFAXEXT@192.168.56.101 entering state [completing][200]
  156. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7058 Remote SDP:
  157. v=0
  158. o=FreeSWITCH 1497330178 1497330179 IN IP4 192.168.56.101
  159. s=FreeSWITCH
  160. c=IN IP4 192.168.56.101
  161. t=0 0
  162. m=audio 31130 RTP/AVP 0 101 13
  163. a=rtpmap:0 PCMU/8000
  164. a=rtpmap:101 telephone-event/8000
  165. a=fmtp:101 0-16
  166. a=rtpmap:13 CN/8000
  167. a=ptime:20
  168.  
  169. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7048 Channel sofia/internal/RECVFAXEXT@192.168.56.101 entering state [ready][200]
  170. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  171. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  172. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  173. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 101@8000
  174. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/RECVFAXEXT@192.168.56.101 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  175. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_codec.c:111 sofia/internal/RECVFAXEXT@192.168.56.101 Original read codec set to PCMU:0
  176. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 101@8000
  177. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:4754 sofia/internal/RECVFAXEXT@192.168.56.101 Set 2833 dtmf send payload to 101 recv payload to 101
  178. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/RECVFAXEXT@192.168.56.101] 192.168.56.101 port 21512 -> 192.168.56.101 port 31130 codec: 0 ms: 20
  179. 2017-06-13 09:41:48.481892 [DEBUG] switch_rtp.c:4114 Not using a timer
  180. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7048 Channel sofia/internal/+123456@192.168.56.101 entering state [completed][200]
  181. 2017-06-13 09:41:48.481892 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/+123456@192.168.56.101] has been answered
  182. 2017-06-13 09:41:48.481892 [DEBUG] switch_channel.c:3772 (sofia/internal/+123456@192.168.56.101) Callstate Change RINGING -> ACTIVE
  183. 2017-06-13 09:41:48.481892 [DEBUG] sofia.c:7048 Channel sofia/internal/+123456@192.168.56.101 entering state [ready][200]
  184. EXECUTE sofia/internal/+123456@192.168.56.101 log(ALERT inside recv_fax)
  185. 2017-06-13 09:41:48.481892 [ALERT] mod_dptools.c:1724 inside recv_fax
  186. EXECUTE sofia/internal/+123456@192.168.56.101 rxfax(/tmp/rx.tiff)
  187. 2017-06-13 09:41:48.481892 [DEBUG] mod_spandsp_fax.c:1448 Raw read codec activation Success L16 20000
  188. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_codec.c:223 sofia/internal/+123456@192.168.56.101 Push codec L16:100
  189. 2017-06-13 09:41:48.481892 [DEBUG] mod_spandsp_fax.c:1466 Raw write codec activation Success L16
  190. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7166 sofia/internal/RECVFAXEXT@192.168.56.101 Set 2833 dtmf send payload to 101
  191. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7173 sofia/internal/RECVFAXEXT@192.168.56.101 Set 2833 dtmf receive payload to 101
  192. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7196 sofia/internal/RECVFAXEXT@192.168.56.101 Set rtp dtmf delay to 40
  193. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_media.c:7202 Set comfort noise payload to 13
  194. 2017-06-13 09:41:48.481892 [NOTICE] sofia.c:8182 Channel [sofia/internal/RECVFAXEXT@192.168.56.101] has been answered
  195. 2017-06-13 09:41:48.481892 [DEBUG] switch_channel.c:3772 (sofia/internal/RECVFAXEXT@192.168.56.101) Callstate Change DOWN -> ACTIVE
  196. 2017-06-13 09:41:48.481892 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/RECVFAXEXT@192.168.56.101]
  197. 2017-06-13 09:41:48.481892 [DEBUG] switch_ivr.c:2165 (sofia/internal/RECVFAXEXT@192.168.56.101) State Change CS_CONSUME_MEDIA -> CS_ROUTING
  198. 2017-06-13 09:41:48.481892 [NOTICE] switch_ivr.c:2172 Transfer sofia/internal/RECVFAXEXT@192.168.56.101 to XML[SENDFAXEXT@default_context]
  199. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/RECVFAXEXT@192.168.56.101) Running State Change CS_ROUTING (Cur 2 Tot 2)
  200. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/RECVFAXEXT@192.168.56.101) State ROUTING
  201. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:143 sofia/internal/RECVFAXEXT@192.168.56.101 SOFIA ROUTING
  202. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:236 sofia/internal/RECVFAXEXT@192.168.56.101 Standard ROUTING
  203. 2017-06-13 09:41:48.481892 [INFO] mod_dialplan_xml.c:637 Processing mycallername <+123456>->SENDFAXEXT in context default_context
  204. Dialplan: sofia/internal/RECVFAXEXT@192.168.56.101 parsing [default_context->send_fax] continue=false
  205. Dialplan: sofia/internal/RECVFAXEXT@192.168.56.101 Regex (PASS) [send_fax] destination_number(SENDFAXEXT) =~ /SENDFAXEXT/ break=on-false
  206. Dialplan: sofia/internal/RECVFAXEXT@192.168.56.101 Action answer()
  207. Dialplan: sofia/internal/RECVFAXEXT@192.168.56.101 Action log(ALERT inside sendfax destination: ${destination_number})
  208. Dialplan: sofia/internal/RECVFAXEXT@192.168.56.101 Action txfax(/tmp/tx.tiff)
  209. Dialplan: sofia/internal/RECVFAXEXT@192.168.56.101 Action hangup()
  210. 2017-06-13 09:41:48.481892 [INFO] switch_channel.c:3129 sofia/internal/RECVFAXEXT@192.168.56.101 Flipping CID from "mycallername" <+123456> to "RECVFAXEXT" <RECVFAXEXT>
  211. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/RECVFAXEXT@192.168.56.101) State Change CS_ROUTING -> CS_EXECUTE
  212. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/RECVFAXEXT@192.168.56.101) State ROUTING going to sleep
  213. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/RECVFAXEXT@192.168.56.101) Running State Change CS_EXECUTE (Cur 2 Tot 2)
  214. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/RECVFAXEXT@192.168.56.101) State EXECUTE
  215. 2017-06-13 09:41:48.481892 [DEBUG] mod_sofia.c:198 sofia/internal/RECVFAXEXT@192.168.56.101 SOFIA EXECUTE
  216. 2017-06-13 09:41:48.481892 [DEBUG] switch_core_state_machine.c:328 sofia/internal/RECVFAXEXT@192.168.56.101 Standard EXECUTE
  217. EXECUTE sofia/internal/RECVFAXEXT@192.168.56.101 answer()
  218. EXECUTE sofia/internal/RECVFAXEXT@192.168.56.101 log(ALERT inside sendfax destination: SENDFAXEXT)
  219. 2017-06-13 09:41:48.481892 [ALERT] mod_dptools.c:1724 inside sendfax destination: SENDFAXEXT
  220. EXECUTE sofia/internal/RECVFAXEXT@192.168.56.101 txfax(/tmp/tx.tiff)
  221. 2017-06-13 09:41:48.521420 [DEBUG] mod_spandsp_fax.c:1448 Raw read codec activation Success L16 20000
  222. 2017-06-13 09:41:48.521420 [DEBUG] switch_core_codec.c:223 sofia/internal/RECVFAXEXT@192.168.56.101 Push codec L16:100
  223. 2017-06-13 09:41:48.521420 [DEBUG] mod_spandsp_fax.c:1466 Raw write codec activation Success L16
  224.