From Harsh, 6 Years ago, written in Plain Text.
- go back
Embed
Viewing differences between and Receive Fax not working
  1. 2017-05-25 12:54:15.508375 [NOTICE] switch_channel.c:1104 New Channel sofia/default/95878797@127.0.0.1:5060 [59fe0ca5-958e-4801-9c69-3ef056fb102f]
  2. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:543 (sofia/default/95878797@127.0.0.1:5060) Running State Change CS_NEW
  3. 2017-05-25 12:54:15.508375 [DEBUG] sofia.c:9376 sofia/default/95878797@127.0.0.1:5060 receiving invite from 127.0.0.1:5060 version: 1.6.8  64bit
  4. 2017-05-25 12:54:15.508375 [DEBUG] sofia.c:9488 IP 127.0.0.1 Approved by acl "default[]". Access Granted.
  5. 2017-05-25 12:54:15.508375 [DEBUG] sofia.c:6858 Channel sofia/default/95878797@127.0.0.1:5060 entering state [received][100]
  6. 2017-05-25 12:54:15.508375 [DEBUG] sofia.c:6868 Remote SDP:
  7. v=0
  8. o=Z 0 0 IN IP4 127.0.0.2
  9. s=Z
  10. c=IN IP4 127.0.0.2
  11. t=0 0
  12. m=audio 8000 RTP/AVP 8 0 101
  13. a=rtpmap:101 telephone-event/8000
  14. a=fmtp:101 0-15
  15.  
  16. 2017-05-25 12:54:15.508375 [DEBUG] sofia.c:7227 (sofia/default/95878797@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  17. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:562 (sofia/default/95878797@127.0.0.1:5060) State NEW
  18. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:543 (sofia/default/95878797@127.0.0.1:5060) Running State Change CS_INIT
  19. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:586 (sofia/default/95878797@127.0.0.1:5060) State INIT
  20. 2017-05-25 12:54:15.508375 [DEBUG] mod_sofia.c:89 sofia/default/95878797@127.0.0.1:5060 SOFIA INIT
  21. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:40 sofia/default/95878797@127.0.0.1:5060 Standard INIT
  22. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:48 (sofia/default/95878797@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  23. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:586 (sofia/default/95878797@127.0.0.1:5060) State INIT going to sleep
  24. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:543 (sofia/default/95878797@127.0.0.1:5060) Running State Change CS_ROUTING
  25. 2017-05-25 12:54:15.508375 [DEBUG] switch_channel.c:2249 (sofia/default/95878797@127.0.0.1:5060) Callstate Change DOWN -> RINGING
  26. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:602 (sofia/default/95878797@127.0.0.1:5060) State ROUTING
  27. 2017-05-25 12:54:15.508375 [DEBUG] mod_sofia.c:142 sofia/default/95878797@127.0.0.1:5060 SOFIA ROUTING
  28. 2017-05-25 12:54:15.508375 [DEBUG] switch_core_state_machine.c:236 sofia/default/95878797@127.0.0.1:5060 Standard ROUTING
  29. 2017-05-25 12:54:15.508375 [INFO] mod_dialplan_xml.c:637 Processing 95878797 <95878797>->123456789 in context default
  30. 2017-05-25 12:54:15.508375 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0b80072ff0 Connected.
  31. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [xml_REQUEST] key_name:
  32. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [xml_REQUEST] key_value:
  33. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [xml_REQUEST] tag_name:
  34. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [xml_REQUEST] section: dialplan
  35. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [Dialplan] Dialed number : 123456789
  36. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [Dialplan] Dialed number : 123456789 Voicemail Number 7777
  37. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="123456789" LIMIT 1
  38. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [Dialplan] Call direction : inbound
  39. 2017-05-25 12:54:15.508375 [NOTICE] switch_cpp.cpp:1360 [TEST_FAX] [Accountcode : 9213634206]
  40. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "9213634206" OR id="9213634206") AND status=0 AND deleted=0 AND (expiry >= '2017-05-25 10:54:15' OR expiry = '0000-00-00 00:00:00') limit 1
  41. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [GET_PACKAGE_INFO] Query :SELECT * FROM packages  as P inner join package_patterns as PKGPTR on P.id = PKGPTR.package_id WHERE (patterns ='^123456789.*' OR patterns ='^9476518391.*' OR patterns ='^947651839.*' OR patterns ='^94765183.*' OR patterns ='^9476518.*' OR patterns ='^947651.*' OR patterns ='^94765.*' OR patterns ='^9476.*' OR patterns ='^947.*' OR patterns ='^94.*' OR patterns ='^9.*' OR patterns ='--') AND status = 0 AND pricelist_id = 22 ORDER BY LENGTH(PKGPTR.patterns) DESC LIMIT 1
  42. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] =============== Account Information ===================
  43. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] User id : 64
  44. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Account code : 9213634206
  45. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Balance : 4.9046
  46. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Type : 0 [0:prepaid,1:postpaid]
  47. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Ratecard id : 22
  48. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] ========================================================
  49. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 22 AND status = 0
  50. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="123456789" LIMIT 1
  51. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] =============== Rates Information ===================
  52. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] ID : 23
  53. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Connectcost : 0.00000
  54. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Includedseconds : 0
  55. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Cost : 0.00000
  56. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] comment : 123456789
  57. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Accid : 64
  58. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] ================================================================
  59. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [FIND_MAXLENGTH] Your balance is to dial 4.9046 !!!
  60. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  61. 2017-05-25 12:54:15.508375 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Call Max length duration : 100 minutes
  62. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "35" OR id="35") AND status=0 AND deleted=0 AND (expiry >= '2017-05-25 10:54:15' OR expiry = '0000-00-00 00:00:00') limit 1
  63. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 22 AND status = 0
  64. 2017-05-25 12:54:15.508375 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern ='^123456789.*' OR pattern ='^9476518391.*' OR pattern ='^947651839.*' OR pattern ='^94765183.*' OR pattern ='^9476518.*' OR pattern ='^947651.*' OR pattern ='^94765.*' OR pattern ='^9476.*' OR pattern ='^947.*' OR pattern ='^94.*' OR pattern ='^9.*' OR pattern ='--') AND status = 0 AND pricelist_id = 22  ORDER BY LENGTH(pattern) DESC,cost DESC LIMIT 1
  65. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] =============== Rates Information ===================
  66. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] ID : 41
  67. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Connectcost : 0.00000
  68. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Includedseconds : 0
  69. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Cost : 0.00370
  70. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] comment : España Fijo
  71. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] Accid : 64
  72. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] ================================================================
  73. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [FIND_MAXLENGTH] Your balance is to dial 4.9046 !!!
  74. 2017-05-25 12:54:15.548289 [INFO] switch_cpp.cpp:1360 [TEST_FAX] [userinfo] Userinfo XML:64
  75. 2017-05-25 12:54:15.548289 [DEBUG] switch_cpp.cpp:1360 [TEST_FAX] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  76. <document type="freeswitch/xml">
  77. <section name="dialplan" description="TEST_FAX Dialplan">
  78. <context name="default">
  79. <extension name="123456789">
  80. <condition field="destination_number" expression="123456789">
  81. <action application="set" data="effective_destination_number=123456789"/>
  82. <action application="sched_hangup" data="+6000 normal_clearing"/>
  83. <action application="set" data="callstart=2017-05-25 10:54:15"/>
  84. <action application="set" data="hangup_after_bridge=true"/>
  85. <action application="set" data="continue_on_fail=true"/>
  86. <action application="set" data="account_id=64"/>
  87. <action application="set" data="parent_id=0"/>
  88. <action application="set" data="entity_id=0"/>
  89. <action application="set" data="call_processed=internal"/>
  90. <action application="set" data="call_direction=inbound"/>
  91. <action application="set" data="accountname=default"/>
  92. <action application="set" data="origination_rates_did=ID:23|CODE:^.123456789.*|DESTINATION:123456789|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:35"/>
  93. <action application="set" data="origination_rates=ID:41|CODE:^9.*|DESTINATION:España Fijo|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00370|INC:0|INITIALBLOCK:0|RATEGROUP:22|MARKUP:0|ACCID:64"/>
  94. <action application="set" data="receiver_accid=35"/>
  95. <action application="set" data="calltype=FAX"/>
  96. <action application="answer"/>
  97. <action application="set" data="callfax=true"/>
  98. <action application="playback" data="silence_stream://2000"/>
  99. <action application="set" data="ignore_early_media=true"/>
  100. <action application="set" data="fax_enable_t38_request=true"/>
  101. <action application="set" data="fax_use_ecm=true"/>
  102. <action application="set" data="fax_enable_t38=true"/>
  103. <action application="set" data="fax_verbose=true"/>
  104. <action application="set" data="session_in_hangup_hook=true"/>
  105. <action application="set" data="api_hangup_hook=system /var/www/html/FAX/scripts/emailfax.sh /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.tif 64 Sip6_tarifa+coste test@test.com /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.pdf"/>
  106. <action application="rxfax" data="/tmp/123-FAX.tif"/>
  107. <action application="hangup"/>
  108. </condition>
  109. </extension>
  110. </context>
  111. </section>
  112. </document>
  113. 2017-05-25 12:54:15.548289 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0b80072ff0 released.
  114. Dialplan: sofia/default/95878797@127.0.0.1:5060 parsing [default->123456789] continue=false
  115. Dialplan: sofia/default/95878797@127.0.0.1:5060 Regex (PASS) [123456789] destination_number(123456789) =~ /123456789/ break=on-false
  116. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(effective_destination_number=123456789)
  117. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action sched_hangup(+6000 normal_clearing)
  118. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(callstart=2017-05-25 10:54:15)
  119. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(hangup_after_bridge=true)
  120. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(continue_on_fail=true)
  121. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(account_id=64)
  122. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(parent_id=0)
  123. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(entity_id=0)
  124. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(call_processed=internal)
  125. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(call_direction=inbound)
  126. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(accountname=default)
  127. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(origination_rates_did=ID:23|CODE:^.123456789.*|DESTINATION:123456789|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:35)
  128. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(origination_rates=ID:41|CODE:^9.*|DESTINATION:España Fijo|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00370|INC:0|INITIALBLOCK:0|RATEGROUP:22|MARKUP:0|ACCID:64)
  129. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(receiver_accid=35)
  130. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(calltype=FAX)
  131. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action answer()
  132. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(callfax=true)
  133. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action playback(silence_stream://2000)
  134. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(ignore_early_media=true)
  135. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(fax_enable_t38_request=true)
  136. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(fax_use_ecm=true)
  137. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(fax_enable_t38=true)
  138. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(fax_verbose=true)
  139. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(session_in_hangup_hook=true)
  140. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action set(api_hangup_hook=system /var/www/html/FAX/scripts/emailfax.sh /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.tif 64 Sip6_tarifa+coste test@test.com /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.pdf)
  141. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action rxfax(/tmp/123-FAX.tif)
  142. Dialplan: sofia/default/95878797@127.0.0.1:5060 Action hangup()
  143. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_state_machine.c:286 (sofia/default/95878797@127.0.0.1:5060) State Change CS_ROUTING -> CS_EXECUTE
  144. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_state_machine.c:602 (sofia/default/95878797@127.0.0.1:5060) State ROUTING going to sleep
  145. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_state_machine.c:543 (sofia/default/95878797@127.0.0.1:5060) Running State Change CS_EXECUTE
  146. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_state_machine.c:609 (sofia/default/95878797@127.0.0.1:5060) State EXECUTE
  147. 2017-05-25 12:54:15.548289 [DEBUG] mod_sofia.c:197 sofia/default/95878797@127.0.0.1:5060 SOFIA EXECUTE
  148. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_state_machine.c:328 sofia/default/95878797@127.0.0.1:5060 Standard EXECUTE
  149. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(effective_destination_number=123456789)
  150. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [effective_destination_number]=[123456789]
  151. EXECUTE sofia/default/95878797@127.0.0.1:5060 sched_hangup(+6000 normal_clearing)
  152. 2017-05-25 12:54:15.548289 [DEBUG] switch_scheduler.c:249 Added task 7 switch_ivr_schedule_hangup (59fe0ca5-958e-4801-9c69-3ef056fb102f) to run at 1495715655
  153. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(callstart=2017-05-25 10:54:15)
  154. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [callstart]=[2017-05-25 10:54:15]
  155. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(hangup_after_bridge=true)
  156. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [hangup_after_bridge]=[true]
  157. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(continue_on_fail=true)
  158. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [continue_on_fail]=[true]
  159. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(account_id=64)
  160. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [account_id]=[64]
  161. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(parent_id=0)
  162. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [parent_id]=[0]
  163. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(entity_id=0)
  164. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [entity_id]=[0]
  165. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(call_processed=internal)
  166. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [call_processed]=[internal]
  167. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(call_direction=inbound)
  168. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [call_direction]=[inbound]
  169. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(accountname=default)
  170. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [accountname]=[default]
  171. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(origination_rates_did=ID:23|CODE:^.123456789.*|DESTINATION:123456789|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:35)
  172. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [origination_rates_did]=[ID:23|CODE:^.123456789.*|DESTINATION:123456789|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:35]
  173. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(origination_rates=ID:41|CODE:^9.*|DESTINATION:España Fijo|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00370|INC:0|INITIALBLOCK:0|RATEGROUP:22|MARKUP:0|ACCID:64)
  174. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [origination_rates]=[ID:41|CODE:^9.*|DESTINATION:España Fijo|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00370|INC:0|INITIALBLOCK:0|RATEGROUP:22|MARKUP:0|ACCID:64]
  175. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(receiver_accid=35)
  176. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [receiver_accid]=[35]
  177. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(calltype=FAX)
  178. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [calltype]=[FAX]
  179. EXECUTE sofia/default/95878797@127.0.0.1:5060 answer()
  180. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  181. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  182. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  183. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  184. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  185. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  186. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  187. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  188. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4271 Set telephone-event payload to 101@8000
  189. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:3090 Set Codec sofia/default/95878797@127.0.0.1:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  190. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_codec.c:111 sofia/default/95878797@127.0.0.1:5060 Original read codec set to PCMA:8
  191. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4623 Set telephone-event payload to 101@8000
  192. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:4681 sofia/default/95878797@127.0.0.1:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  193. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:6464 AUDIO RTP [sofia/default/95878797@127.0.0.1:5060] 127.0.0.3 port 25770 -> 127.0.0.2 port 8000 codec: 8 ms: 20
  194. 2017-05-25 12:54:15.548289 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
  195. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:6763 sofia/default/95878797@127.0.0.1:5060 Set 2833 dtmf send payload to 101
  196. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:6770 sofia/default/95878797@127.0.0.1:5060 Set 2833 dtmf receive payload to 101
  197. 2017-05-25 12:54:15.548289 [DEBUG] switch_core_media.c:6793 sofia/default/95878797@127.0.0.1:5060 Set rtp dtmf delay to 40
  198. 2017-05-25 12:54:15.548289 [NOTICE] sofia_media.c:92 Pre-Answer sofia/default/95878797@127.0.0.1:5060!
  199. 2017-05-25 12:54:15.548289 [DEBUG] switch_channel.c:3471 (sofia/default/95878797@127.0.0.1:5060) Callstate Change RINGING -> EARLY
  200. 2017-05-25 12:54:15.548289 [DEBUG] mod_sofia.c:814 Local SDP sofia/default/95878797@127.0.0.1:5060:
  201. v=0
  202. o=FreeSWITCH 1495683885 1495683886 IN IP4 127.0.0.3
  203. s=FreeSWITCH
  204. c=IN IP4 127.0.0.3
  205. t=0 0
  206. m=audio 25770 RTP/AVP 8 101
  207. a=rtpmap:8 PCMA/8000
  208. a=rtpmap:101 telephone-event/8000
  209. a=fmtp:101 0-16
  210. a=ptime:20
  211. a=sendrecv
  212.  
  213. 2017-05-25 12:54:15.548289 [NOTICE] mod_dptools.c:1309 Channel [sofia/default/95878797@127.0.0.1:5060] has been answered
  214. send 1021 bytes to udp/[127.0.0.1]:5060 at 12:54:15.555337:
  215.    ------------------------------------------------------------------------
  216.    SIP/2.0 200 OK
  217.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK6aab.be435b72.1
  218.    Via: SIP/2.0/UDP 127.0.0.2:36676;branch=z9hG4bK-d8754z-39f7e02283df1ba2-1---d8754z-
  219.    Record-Route: <sip:127.0.0.1;lr;did=27a.b04c6494>
  220.    From: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  221.    To: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  222.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  223.    CSeq: 2 INVITE
  224.    Contact: <sip:123456789@127.0.0.3:7777;transport=udp>
  225.    User-Agent: TEST_FAX
  226.    Accept: application/sdp
  227.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  228.    Supported: timer, path, replaces
  229.    Allow-Events: talk, hold, conference, refer
  230.    Content-Type: application/sdp
  231.    Content-Disposition: session
  232.    Content-Length: 224
  233.    
  234.    v=0
  235.    o=FreeSWITCH 1495683885 1495683886 IN IP4 127.0.0.3
  236.    s=FreeSWITCH
  237.    c=IN IP4 127.0.0.3
  238.    t=0 0
  239.    m=audio 25770 RTP/AVP 8 101
  240.    a=rtpmap:8 PCMA/8000
  241.    a=rtpmap:101 telephone-event/8000
  242.    a=fmtp:101 0-16
  243.    a=ptime:20
  244.    ------------------------------------------------------------------------
  245. 2017-05-25 12:54:15.548289 [DEBUG] switch_channel.c:3770 (sofia/default/95878797@127.0.0.1:5060) Callstate Change EARLY -> ACTIVE
  246. 2017-05-25 12:54:15.548289 [DEBUG] sofia.c:6858 Channel sofia/default/95878797@127.0.0.1:5060 entering state [completed][200]
  247. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(callfax=true)
  248. 2017-05-25 12:54:15.548289 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [callfax]=[true]
  249. EXECUTE sofia/default/95878797@127.0.0.1:5060 playback(silence_stream://2000)
  250. 2017-05-25 12:54:15.548289 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
  251. recv 939 bytes from udp/[127.0.0.1]:5060 at 12:54:15.769974:
  252.    ------------------------------------------------------------------------
  253.    ACK sip:123456789@127.0.0.3:7777;transport=udp SIP/2.0
  254.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK6aab.be435b72.3
  255.    Via: SIP/2.0/UDP 127.0.0.2:36676;branch=z9hG4bK-d8754z-7d9646c55b1fa2ff-1---d8754z-
  256.    Max-Forwards: 30
  257.    Contact: <sip:95878797@127.0.0.2:36676;transport=UDP>
  258.    To: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  259.    From: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  260.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  261.    CSeq: 2 ACK
  262.    Proxy-Authorization: Digest username="95878797",realm="127.0.0.1",nonce="5926b7f400001de81b704f6044ebbf3fcc509038f7b7efa4",uri="sip:123456789@127.0.0.1;transport=UDP",response="b81f6beb76103b488863ffafa71feec8",algorithm=MD5
  263.    User-Agent: Z 3.3.25608 r25552
  264.    Content-Length: 0
  265.    P-hint: rr-enforced
  266.    X-AUTH-IP: 127.0.0.2
  267.    P-Accountcode: 4118807230
  268.    P-effective_caller_id_name:
  269.    P-effective_caller_id_number:
  270.    
  271.    ------------------------------------------------------------------------
  272. 2017-05-25 12:54:15.748366 [DEBUG] sofia.c:6858 Channel sofia/default/95878797@127.0.0.1:5060 entering state [ready][200]
  273. recv 345 bytes from udp/[127.0.0.1]:7000 at 12:54:16.371165:
  274.    ------------------------------------------------------------------------
  275.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  276.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK3ffc.b9334371.0
  277.    To: sip:127.0.0.3:7777
  278.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-f156
  279.    CSeq: 14 OPTIONS
  280.    Call-ID: 2a3b058977bb0c08-3294@127.0.0.1
  281.    Max-Forwards: 70
  282.    Content-Length: 0
  283.    User-Agent: SIP Proxy
  284.    
  285.    ------------------------------------------------------------------------
  286. send 541 bytes to udp/[127.0.0.1]:7000 at 12:54:16.371439:
  287.    ------------------------------------------------------------------------
  288.    SIP/2.0 200 OK
  289.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK3ffc.b9334371.0
  290.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-f156
  291.    To: <sip:127.0.0.3:7777>;tag=H44X0gvayN06H
  292.    Call-ID: 2a3b058977bb0c08-3294@127.0.0.1
  293.    CSeq: 14 OPTIONS
  294.    Contact: <sip:127.0.0.3:7777>
  295.    User-Agent: TEST_FAX
  296.    Accept: application/sdp
  297.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  298.    Supported: timer, path, replaces
  299.    Allow-Events: talk, hold, conference, refer
  300.    Content-Length: 0
  301.    
  302.    ------------------------------------------------------------------------
  303. recv 345 bytes from udp/[127.0.0.1]:7000 at 12:54:17.373476:
  304.    ------------------------------------------------------------------------
  305.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  306.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK8d06.4a5e35b7.0
  307.    To: sip:127.0.0.3:7777
  308.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-7a90
  309.    CSeq: 14 OPTIONS
  310.    Call-ID: 2a3b058977bb0d34-3302@127.0.0.1
  311.    Max-Forwards: 70
  312.    Content-Length: 0
  313.    User-Agent: SIP Proxy
  314.    
  315.    ------------------------------------------------------------------------
  316. send 541 bytes to udp/[127.0.0.1]:7000 at 12:54:17.373725:
  317.    ------------------------------------------------------------------------
  318.    SIP/2.0 200 OK
  319.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK8d06.4a5e35b7.0
  320.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-7a90
  321.    To: <sip:127.0.0.3:7777>;tag=jDyp2BDeUypSD
  322.    Call-ID: 2a3b058977bb0d34-3302@127.0.0.1
  323.    CSeq: 14 OPTIONS
  324.    Contact: <sip:127.0.0.3:7777>
  325.    User-Agent: TEST_FAX
  326.    Accept: application/sdp
  327.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  328.    Supported: timer, path, replaces
  329.    Allow-Events: talk, hold, conference, refer
  330.    Content-Length: 0
  331.    
  332.    ------------------------------------------------------------------------
  333. 2017-05-25 12:54:17.548427 [DEBUG] switch_ivr_play_say.c:1910 done playing file silence_stream://2000
  334. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(ignore_early_media=true)
  335. 2017-05-25 12:54:17.548427 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [ignore_early_media]=[true]
  336. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(fax_enable_t38_request=true)
  337. 2017-05-25 12:54:17.548427 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [fax_enable_t38_request]=[true]
  338. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(fax_use_ecm=true)
  339. 2017-05-25 12:54:17.548427 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [fax_use_ecm]=[true]
  340. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(fax_enable_t38=true)
  341. 2017-05-25 12:54:17.548427 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [fax_enable_t38]=[true]
  342. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(fax_verbose=true)
  343. 2017-05-25 12:54:17.548427 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [fax_verbose]=[true]
  344. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(session_in_hangup_hook=true)
  345. 2017-05-25 12:54:17.548427 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [session_in_hangup_hook]=[true]
  346. EXECUTE sofia/default/95878797@127.0.0.1:5060 set(api_hangup_hook=system /var/www/html/FAX/scripts/emailfax.sh /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.tif 64 Sip6_tarifa+coste test@test.com /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.pdf)
  347. 2017-05-25 12:54:17.548427 [DEBUG] mod_dptools.c:1519 SET sofia/default/95878797@127.0.0.1:5060 [api_hangup_hook]=[system /var/www/html/FAX/scripts/emailfax.sh /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.tif 64 Sip6_tarifa+coste test@test.com /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.pdf]
  348. EXECUTE sofia/default/95878797@127.0.0.1:5060 rxfax(/tmp/123-FAX.tif)
  349. 2017-05-25 12:54:17.548427 [DEBUG] mod_spandsp_fax.c:1440 Raw read codec activation Success L16 20000
  350. 2017-05-25 12:54:17.548427 [DEBUG] switch_core_codec.c:221 sofia/default/95878797@127.0.0.1:5060 Push codec L16:100
  351. 2017-05-25 12:54:17.548427 [DEBUG] mod_spandsp_fax.c:1458 Raw write codec activation Success L16
  352. recv 343 bytes from udp/[127.0.0.4]:7000 at 12:54:18.376658:
  353.    ------------------------------------------------------------------------
  354.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  355.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKd3f.1399208.0
  356.    To: sip:127.0.0.3:7777
  357.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-4bc1
  358.    CSeq: 14 OPTIONS
  359.    Call-ID: 2a3b058977bb0cd8-3330@127.0.0.4
  360.    Max-Forwards: 70
  361.    Content-Length: 0
  362.    User-Agent: SIP Proxy
  363.    
  364.    ------------------------------------------------------------------------
  365. send 563 bytes to udp/[127.0.0.4]:7000 at 12:54:18.376855:
  366.    ------------------------------------------------------------------------
  367.    SIP/2.0 200 OK
  368.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKd3f.1399208.0;received=127.0.0.4
  369.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-4bc1
  370.    To: <sip:127.0.0.3:7777>;tag=KpQF46XHr7ccS
  371.    Call-ID: 2a3b058977bb0cd8-3330@127.0.0.4
  372.    CSeq: 14 OPTIONS
  373.    Contact: <sip:127.0.0.3:7777>
  374.    User-Agent: TEST_FAX
  375.    Accept: application/sdp
  376.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  377.    Supported: timer, path, replaces
  378.    Allow-Events: talk, hold, conference, refer
  379.    Content-Length: 0
  380.    
  381.    ------------------------------------------------------------------------
  382. 2017-05-25 12:54:18.808383 [DEBUG] switch_core_media.c:8901 sofia/default/95878797@127.0.0.1:5060 image media sdp:
  383. v=0
  384. o=FreeSWITCH 1495683885 1495683887 IN IP4 127.0.0.3
  385. s=FreeSWITCH
  386. c=IN IP4 127.0.0.3
  387. t=0 0
  388. m=image 25770 udptl t38
  389. a=T38FaxVersion:0
  390. a=T38MaxBitRate:14400
  391. a=T38FaxFillBitRemoval
  392. a=T38FaxRateManagement:transferredTCF
  393. a=T38FaxMaxBuffer:2000
  394. a=T38FaxMaxDatagram:400
  395. a=T38FaxUdpEC:t38UDPRedundancy
  396.  
  397. 2017-05-25 12:54:18.808383 [DEBUG] sofia_glue.c:1257 sofia/default/95878797@127.0.0.1:5060 sending invite version: 1.6.8  64bit
  398. Local SDP:
  399. v=0
  400. o=FreeSWITCH 1495683885 1495683887 IN IP4 127.0.0.3
  401. s=FreeSWITCH
  402. c=IN IP4 127.0.0.3
  403. t=0 0
  404. m=image 25770 udptl t38
  405. a=T38FaxVersion:0
  406. a=T38MaxBitRate:14400
  407. a=T38FaxFillBitRemoval
  408. a=T38FaxRateManagement:transferredTCF
  409. a=T38FaxMaxBuffer:2000
  410. a=T38FaxMaxDatagram:400
  411. a=T38FaxUdpEC:t38UDPRedundancy
  412.  
  413. send 1081 bytes to udp/[127.0.0.1]:5060 at 12:54:18.813525:
  414.    ------------------------------------------------------------------------
  415.    INVITE sip:95878797@127.0.0.2:36676;transport=UDP SIP/2.0
  416.    Via: SIP/2.0/UDP 127.0.0.3:7777;rport;branch=z9hG4bK06QmrpUmDry9B
  417.    Route: <sip:127.0.0.1;lr;did=27a.b04c6494>
  418.    Max-Forwards: 30
  419.    From: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  420.    To: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  421.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  422.    CSeq: 107518765 INVITE
  423.    Contact: <sip:123456789@127.0.0.3:7777;transport=udp>
  424.    User-Agent: TEST_FAX
  425.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  426.    Supported: timer, path, replaces
  427.    Content-Type: application/sdp
  428.    Content-Length: 320
  429.    P-Accountcode: 9213634206
  430.    X-AUTH-IP: 127.0.0.2
  431.    P-Accountcode-1: 4118807230
  432.    
  433.    v=0
  434.    o=FreeSWITCH 1495683885 1495683887 IN IP4 127.0.0.3
  435.    s=FreeSWITCH
  436.    c=IN IP4 127.0.0.3
  437.    t=0 0
  438.    m=image 25770 udptl t38
  439.    a=T38FaxVersion:0
  440.    a=T38MaxBitRate:14400
  441.    a=T38FaxFillBitRemoval
  442.    a=T38FaxRateManagement:transferredTCF
  443.    a=T38FaxMaxBuffer:2000
  444.    a=T38FaxMaxDatagram:400
  445.    a=T38FaxUdpEC:t38UDPRedundancy
  446.    ------------------------------------------------------------------------
  447. recv 386 bytes from udp/[127.0.0.1]:5060 at 12:54:18.814212:
  448.    ------------------------------------------------------------------------
  449.    SIP/2.0 100 Giving a try
  450.    Via: SIP/2.0/UDP 127.0.0.3:7777;received=127.0.0.3;rport=7777;branch=z9hG4bK06QmrpUmDry9B
  451.    From: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  452.    To: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  453.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  454.    CSeq: 107518765 INVITE
  455.    server: SBC02
  456.    Content-Length: 0
  457.    
  458.    ------------------------------------------------------------------------
  459. 2017-05-25 12:54:18.808383 [DEBUG] sofia.c:6858 Channel sofia/default/95878797@127.0.0.1:5060 entering state [calling][0]
  460. recv 1056 bytes from udp/[127.0.0.1]:5060 at 12:54:19.045470:
  461.    ------------------------------------------------------------------------
  462.    SIP/2.0 200 OK
  463.    Via: SIP/2.0/UDP 127.0.0.3:7777;received=127.0.0.3;rport=7777;branch=z9hG4bK06QmrpUmDry9B
  464.    Contact: <sip:95878797@127.0.0.2:36676;transport=UDP>
  465.    To: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  466.    From: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  467.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  468.    CSeq: 107518765 INVITE
  469.    Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  470.    Content-Type: application/sdp
  471.    Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
  472.    User-Agent: Z 3.3.25608 r25552
  473.    Allow-Events: presence, kpml
  474.    Content-Length: 381
  475.    
  476.    v=0
  477.    o=Z 0 1 IN IP4 127.0.0.2
  478.    s=Z
  479.    c=IN IP4 127.0.0.2
  480.    t=0 0
  481.    m=image 8000 udptl t38
  482.    a=T38FaxVersion:0
  483.    a=T38MaxBitRate:14400
  484.    a=T38FaxFillBitRemoval:0
  485.    a=T38FaxTranscodingMMR:0
  486.    a=T38FaxTranscodingJBIG:0
  487.    a=T38FaxRateManagement:transferredTCF
  488.    a=T38FaxMaxBuffer:400
  489.    a=T38FaxMaxDatagram:400
  490.    a=T38FaxUdpEC:t38UDPRedundancy
  491.    a=oldoip:172.16.16.2
  492.    a=oldcip:172.16.16.2
  493.    ------------------------------------------------------------------------
  494. 2017-05-25 12:54:19.028379 [DEBUG] sofia.c:6858 Channel sofia/default/95878797@127.0.0.1:5060 entering state [completing][200]
  495. 2017-05-25 12:54:19.028379 [DEBUG] sofia.c:6868 Remote SDP:
  496. v=0
  497. o=Z 0 1 IN IP4 127.0.0.2
  498. s=Z
  499. c=IN IP4 127.0.0.2
  500. t=0 0
  501. m=image 8000 udptl t38
  502. a=T38FaxVersion:0
  503. a=T38MaxBitRate:14400
  504. a=T38FaxFillBitRemoval:0
  505. a=T38FaxTranscodingMMR:0
  506. a=T38FaxTranscodingJBIG:0
  507. a=T38FaxRateManagement:transferredTCF
  508. a=T38FaxMaxBuffer:400
  509. a=T38FaxMaxDatagram:400
  510. a=T38FaxUdpEC:t38UDPRedundancy
  511. a=oldoip:172.16.16.2
  512. a=oldcip:172.16.16.2
  513.  
  514. send 441 bytes to udp/[127.0.0.1]:5060 at 12:54:19.066530:
  515.    ------------------------------------------------------------------------
  516.    ACK sip:95878797@127.0.0.2:36676;transport=UDP SIP/2.0
  517.    Via: SIP/2.0/UDP 127.0.0.3:7777;rport;branch=z9hG4bK1FHDtHcra1mvQ
  518.    Route: <sip:127.0.0.1;lr;did=27a.b04c6494>
  519.    Max-Forwards: 70
  520.    From: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  521.    To: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  522.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  523.    CSeq: 107518765 ACK
  524.    Content-Length: 0
  525.    
  526.    ------------------------------------------------------------------------
  527. 2017-05-25 12:54:19.068308 [DEBUG] sofia.c:6858 Channel sofia/default/95878797@127.0.0.1:5060 entering state [ready][200]
  528. 2017-05-25 12:54:19.068308 [DEBUG] switch_core_media.c:9262 Remote address:port [127.0.0.2:8000] has not changed.
  529. 2017-05-25 12:54:19.068308 [DEBUG] switch_core_media.c:9262 Remote address:port [127.0.0.2:8000] has not changed.
  530. recv 338 bytes from udp/[127.0.0.4]:7000 at 12:54:19.379153:
  531.    ------------------------------------------------------------------------
  532.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  533.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKb068.b13494f2.0
  534.    To: sip:127.0.0.3:7777
  535.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-9e25
  536.    CSeq: 14 OPTIONS
  537.    Call-ID: 2a3b058977bb0c16-3360@0.0.0.0
  538.    Max-Forwards: 70
  539.    Content-Length: 0
  540.    User-Agent: SIP Proxy
  541.    
  542.    ------------------------------------------------------------------------
  543. send 558 bytes to udp/[127.0.0.4]:7000 at 12:54:19.379399:
  544.    ------------------------------------------------------------------------
  545.    SIP/2.0 200 OK
  546.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKb068.b13494f2.0;received=127.0.0.4
  547.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-9e25
  548.    To: <sip:127.0.0.3:7777>;tag=mZg851eNNg3ym
  549.    Call-ID: 2a3b058977bb0c16-3360@0.0.0.0
  550.    CSeq: 14 OPTIONS
  551.    Contact: <sip:127.0.0.3:7777>
  552.    User-Agent: TEST_FAX
  553.    Accept: application/sdp
  554.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  555.    Supported: timer, path, replaces
  556.    Allow-Events: talk, hold, conference, refer
  557.    Content-Length: 0
  558.    
  559.    ------------------------------------------------------------------------
  560. 2017-05-25 12:54:19.848399 [DEBUG] switch_rtp.c:6707 Correct audio ip/port confirmed.
  561. 2017-05-25 12:54:19.848399 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present
  562. 2017-05-25 12:54:19.848399 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 CED tone is present
  563. 2017-05-25 12:54:19.848399 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present
  564. 2017-05-25 12:54:20.008389 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present
  565. 2017-05-25 12:54:20.008389 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING
  566. 2017-05-25 12:54:20.008389 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state ANSWERING
  567. 2017-05-25 12:54:20.008389 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase A_CED to B_RX
  568. 2017-05-25 12:54:20.008389 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4
  569. 2017-05-25 12:54:20.008389 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0
  570. recv 338 bytes from udp/[127.0.0.4]:5060 at 12:54:20.382278:
  571.    ------------------------------------------------------------------------
  572.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  573.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK6165.eafc8852.0
  574.    To: sip:127.0.0.3:7777
  575.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-ed56
  576.    CSeq: 14 OPTIONS
  577.    Call-ID: 2a3b058977bb0d2e-3354@0.0.0.0
  578.    Max-Forwards: 70
  579.    Content-Length: 0
  580.    User-Agent: SIP Proxy
  581.    
  582.    ------------------------------------------------------------------------
  583. send 558 bytes to udp/[127.0.0.4]:5060 at 12:54:20.382542:
  584.    ------------------------------------------------------------------------
  585.    SIP/2.0 200 OK
  586.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK6165.eafc8852.0;received=127.0.0.4
  587.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-ed56
  588.    To: <sip:127.0.0.3:7777>;tag=N8907vZrjSSHg
  589.    Call-ID: 2a3b058977bb0d2e-3354@0.0.0.0
  590.    CSeq: 14 OPTIONS
  591.    Contact: <sip:127.0.0.3:7777>
  592.    User-Agent: TEST_FAX
  593.    Accept: application/sdp
  594.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  595.    Supported: timer, path, replaces
  596.    Allow-Events: talk, hold, conference, refer
  597.    Content-Length: 0
  598.    
  599.    ------------------------------------------------------------------------
  600. 2017-05-25 12:54:20.728420 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CSI - CRC OK (clean)
  601. 2017-05-25 12:54:20.728420 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop none (0 remaining)
  602. 2017-05-25 12:54:20.728420 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  CSI without final frame tag
  603. 2017-05-25 12:54:20.728420 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 03 40 31 31 31 31 30 30 30 30 20 20 20 20 20 20 20 20 20 20 20 20
  604. 2017-05-25 12:54:20.728420 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Remote gave CSI as: "00001111"
  605. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type DIS - CRC OK, sig end (clean)
  606. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop none (0 remaining)
  607. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  DIS with final frame tag
  608. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 13 80 04 ee fa 80 80 9f 80 80 80 80 80 80 80 1f
  609. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state ANSWERING
  610. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS/DTC before DIS
  611. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX
  612. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS:
  613. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
  614. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .1..= Real-time Internet fax (T.38): Set
  615. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= 3G mobile network: Not set
  616. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= V.8 capabilities: Not set
  617. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Preferred octets: 256 octets
  618. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
  619. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..1.= Can receive fax: Set
  620. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
  621. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
  622. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= 2-D coding: Set
  623. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1%
  624. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 10..= Recording length: Unlimited
  625. 2017-05-25 12:54:20.988379 [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
  626. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  627. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
  628. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Error correction mode (ECM): Non-ECM
  629. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= T.6 coding: Not set
  630. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  631. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= "Field not valid" supported: Not set
  632. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Multiple selective polling: Not set
  633. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Polled sub-address: Not set
  634. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= T.43 coding: Not set
  635. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= Plane interleave: Not set
  636. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
  637. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
  638. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  639. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...1= R8x15.4lines/mm: Set
  640. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..1.= 300x300pels/25.4mm: Set
  641. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set
  642. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 1...= Inch-based resolution preferred: Set
  643. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...1 ....= Metric-based resolution preferred: Set
  644. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
  645. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Selective polling: Not set
  646. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  647. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Sub-addressing: Not set
  648. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Password: Not set
  649. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
  650. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
  651. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
  652. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
  653. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  654. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
  655. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
  656. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= Character mode: Not set
  657. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
  658. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  659. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
  660. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Digital network capability: Not set
  661. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Duplex capability: Half only
  662. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= JPEG coding: Not set
  663. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= Full colour mode: Not set
  664. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= 12bits/pel component: Not set
  665. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  666. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
  667. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Custom illuminant: Not set
  668. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Custom gamut range: Not set
  669. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= North American Letter (215.9mm x 279.4mm): Not set
  670. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= North American Legal (215.9mm x 355.6mm): Not set
  671. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
  672. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
  673. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  674. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= HKM key management: Not set
  675. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= RSA key management: Not set
  676. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Override: Not set
  677. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= HFX40 cipher: Not set
  678. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= Alternative cipher number 2: Not set
  679. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Alternative cipher number 3: Not set
  680. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= HFX40-I hashing: Not set
  681. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  682. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Alternative hashing system 2: Not set
  683. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Alternative hashing system 3: Not set
  684. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Reserved for future security features: Not set
  685. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..00 0...= T.44 (Mixed Raster Content): 0
  686. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Page length maximum stripe size for T.44 (Mixed Raster Content): Not set
  687. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  688. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Colour/gray-scale 300pels/25.4mm x 300lines/25.4mm or 400pels/25.4mm x 400lines/25.4mm resolution: Not set
  689. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= 100pels/25.4mm x 100lines/25.4mm for colour/gray scale: Not set
  690. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Simple phase C BFT negotiations: Not set
  691. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= Extended BFT Negotiations capable: Not set
  692. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= Internet Selective Polling address (ISP): Not set
  693. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Internet Routing Address (IRA): Not set
  694. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
  695. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...1= 600pels/25.4mm x 600lines/25.4mm: Set
  696. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..1.= 1200pels/25.4mm x 1200lines/25.4mm: Set
  697. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .1..= 300pels/25.4mm x 600lines/25.4mm: Set
  698. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 1...= 400pels/25.4mm x 800lines/25.4mm: Set
  699. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...1 ....= 600pels/25.4mm x 1200lines/25.4mm: Set
  700. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Colour/gray scale 600pels/25.4mm x 600lines/25.4mm: Not set
  701. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Colour/gray scale 1200pels/25.4mm x 1200lines/25.4mm: Not set
  702. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   0... ....= Extension indicator: Not set
  703. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:317 === Negotiation Result =======================================================
  704. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:318 Remote station id: 00001111
  705. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:319 Local station id:  TEST_FAX
  706. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:320 Transfer Rate:     14400
  707. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:322 ECM status         off
  708. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:323 remote country:  
  709. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:324 remote vendor:    
  710. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:325 remote model:    
  711. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:327 ==============================================================================
  712. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS - nothing to send
  713. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Trying to receive file '/tmp/123-FAX.tif'
  714. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS far end cannot transmit
  715. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Status changing to 'Far end is not able to transmit'
  716. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Flushing queued phase B_TX
  717. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase D_TX
  718. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state ANSWERING to C
  719. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  DCN with final frame tag
  720. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 13 fb
  721. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state C
  722. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to D_TX
  723. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  724. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4
  725. 2017-05-25 12:54:20.988379 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present
  726. recv 344 bytes from udp/[127.0.0.1]:5060 at 12:54:21.385787:
  727.    ------------------------------------------------------------------------
  728.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  729.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK10f7.7e633b7.0
  730.    To: sip:127.0.0.3:7777
  731.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-e776
  732.    CSeq: 14 OPTIONS
  733.    Call-ID: 2a3b058977bb0a7c-3288@127.0.0.1
  734.    Max-Forwards: 70
  735.    Content-Length: 0
  736.    User-Agent: SIP Proxy
  737.    
  738.    ------------------------------------------------------------------------
  739. send 540 bytes to udp/[127.0.0.1]:5060 at 12:54:21.386050:
  740.    ------------------------------------------------------------------------
  741.    SIP/2.0 200 OK
  742.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK10f7.7e633b7.0
  743.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-e776
  744.    To: <sip:127.0.0.3:7777>;tag=pH3S9QgvF2F4B
  745.    Call-ID: 2a3b058977bb0a7c-3288@127.0.0.1
  746.    CSeq: 14 OPTIONS
  747.    Contact: <sip:127.0.0.3:7777>
  748.    User-Agent: TEST_FAX
  749.    Accept: application/sdp
  750.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  751.    Supported: timer, path, replaces
  752.    Allow-Events: talk, hold, conference, refer
  753.    Content-Length: 0
  754.    
  755.    ------------------------------------------------------------------------
  756. recv 338 bytes from udp/[127.0.0.4]:5060 at 12:54:22.388596:
  757.    ------------------------------------------------------------------------
  758.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  759.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK6f3e.1e8653a4.0
  760.    To: sip:127.0.0.3:7777
  761.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-9de2
  762.    CSeq: 14 OPTIONS
  763.    Call-ID: 2a3b058977bb0cce-3342@0.0.0.0
  764.    Max-Forwards: 70
  765.    Content-Length: 0
  766.    User-Agent: SIP Proxy
  767.    
  768.    ------------------------------------------------------------------------
  769. send 558 bytes to udp/[127.0.0.4]:5060 at 12:54:22.388954:
  770.    ------------------------------------------------------------------------
  771.    SIP/2.0 200 OK
  772.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK6f3e.1e8653a4.0;received=127.0.0.4
  773.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-9de2
  774.    To: <sip:127.0.0.3:7777>;tag=QtvjBK1ZcB6pQ
  775.    Call-ID: 2a3b058977bb0cce-3342@0.0.0.0
  776.    CSeq: 14 OPTIONS
  777.    Contact: <sip:127.0.0.3:7777>
  778.    User-Agent: TEST_FAX
  779.    Accept: application/sdp
  780.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  781.    Supported: timer, path, replaces
  782.    Allow-Events: talk, hold, conference, refer
  783.    Content-Length: 0
  784.    
  785.    ------------------------------------------------------------------------
  786. recv 345 bytes from udp/[127.0.0.1]:7000 at 12:54:23.391417:
  787.    ------------------------------------------------------------------------
  788.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  789.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK9cdd.c3786c52.0
  790.    To: sip:127.0.0.3:7777
  791.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-193f
  792.    CSeq: 14 OPTIONS
  793.    Call-ID: 2a3b058977bb0d9e-3306@127.0.0.1
  794.    Max-Forwards: 70
  795.    Content-Length: 0
  796.    User-Agent: SIP Proxy
  797.    
  798.    ------------------------------------------------------------------------
  799. send 541 bytes to udp/[127.0.0.1]:7000 at 12:54:23.391659:
  800.    ------------------------------------------------------------------------
  801.    SIP/2.0 200 OK
  802.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK9cdd.c3786c52.0
  803.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-193f
  804.    To: <sip:127.0.0.3:7777>;tag=r3NBDej39Kv9j
  805.    Call-ID: 2a3b058977bb0d9e-3306@127.0.0.1
  806.    CSeq: 14 OPTIONS
  807.    Contact: <sip:127.0.0.3:7777>
  808.    User-Agent: TEST_FAX
  809.    Accept: application/sdp
  810.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  811.    Supported: timer, path, replaces
  812.    Allow-Events: talk, hold, conference, refer
  813.    Content-Length: 0
  814.    
  815.    ------------------------------------------------------------------------
  816. 2017-05-25 12:54:23.408383 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C
  817. 2017-05-25 12:54:23.408383 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C
  818. 2017-05-25 12:54:23.408383 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting final pause before disconnecting
  819. 2017-05-25 12:54:23.408383 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_TX to E
  820. 2017-05-25 12:54:23.408383 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0
  821. 2017-05-25 12:54:23.408383 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 1
  822. 2017-05-25 12:54:23.408383 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state C to B
  823. recv 344 bytes from udp/[127.0.0.4]:7000 at 12:54:24.394321:
  824.    ------------------------------------------------------------------------
  825.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  826.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKb6f.dbf2aa56.0
  827.    To: sip:127.0.0.3:7777
  828.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-7854
  829.    CSeq: 14 OPTIONS
  830.    Call-ID: 2a3b058977bb0cda-3330@127.0.0.4
  831.    Max-Forwards: 70
  832.    Content-Length: 0
  833.    User-Agent: SIP Proxy
  834.    
  835.    ------------------------------------------------------------------------
  836. send 564 bytes to udp/[127.0.0.4]:7000 at 12:54:24.394564:
  837.    ------------------------------------------------------------------------
  838.    SIP/2.0 200 OK
  839.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKb6f.dbf2aa56.0;received=127.0.0.4
  840.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-7854
  841.    To: <sip:127.0.0.3:7777>;tag=ScF4e9266vjve
  842.    Call-ID: 2a3b058977bb0cda-3330@127.0.0.4
  843.    CSeq: 14 OPTIONS
  844.    Contact: <sip:127.0.0.3:7777>
  845.    User-Agent: TEST_FAX
  846.    Accept: application/sdp
  847.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  848.    Supported: timer, path, replaces
  849.    Allow-Events: talk, hold, conference, refer
  850.    Content-Length: 0
  851.    
  852.    ------------------------------------------------------------------------
  853. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase E, state B
  854. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:496 ==============================================================================
  855. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:508 Fax processing not successful - result (10) Far end is not able to transmit.
  856. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:513 Remote station id: 00001111
  857. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:514 Local station id:  TEST_FAX
  858. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 0
  859. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:517 Total fax pages:   0
  860. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:518 Image resolution:  0x0
  861. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate:     14400
  862. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:521 ECM status         off
  863. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:522 remote country:  
  864. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:523 remote vendor:    
  865. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:524 remote model:    
  866. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:526 ==============================================================================
  867. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state B to CALL_FINISHED
  868. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase E to CALL_FINISHED
  869. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 9
  870. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 9
  871. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T FAX exchange complete
  872. 2017-05-25 12:54:24.728348 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Call completed
  873. recv 344 bytes from udp/[127.0.0.1]:7000 at 12:54:25.406040:
  874.    ------------------------------------------------------------------------
  875.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  876.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKd9c.59b22373.0
  877.    To: sip:127.0.0.3:7777
  878.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-f7e6
  879.    CSeq: 14 OPTIONS
  880.    Call-ID: 2a3b058977bb0da0-3306@127.0.0.1
  881.    Max-Forwards: 70
  882.    Content-Length: 0
  883.    User-Agent: SIP Proxy
  884.    
  885.    ------------------------------------------------------------------------
  886. send 540 bytes to udp/[127.0.0.1]:7000 at 12:54:25.406332:
  887.    ------------------------------------------------------------------------
  888.    SIP/2.0 200 OK
  889.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bKd9c.59b22373.0
  890.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-f7e6
  891.    To: <sip:127.0.0.3:7777>;tag=tN8vg4Ka458ea
  892.    Call-ID: 2a3b058977bb0da0-3306@127.0.0.1
  893.    CSeq: 14 OPTIONS
  894.    Contact: <sip:127.0.0.3:7777>
  895.    User-Agent: TEST_FAX
  896.    Accept: application/sdp
  897.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  898.    Supported: timer, path, replaces
  899.    Allow-Events: talk, hold, conference, refer
  900.    Content-Length: 0
  901.    
  902.    ------------------------------------------------------------------------
  903. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_codec.c:246 sofia/default/95878797@127.0.0.1:5060 Restore previous codec PCMA:8.
  904. EXECUTE sofia/default/95878797@127.0.0.1:5060 hangup()
  905. 2017-05-25 12:54:26.028382 [NOTICE] mod_dptools.c:1283 Hangup sofia/default/95878797@127.0.0.1:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  906. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_session.c:2796 sofia/default/95878797@127.0.0.1:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
  907. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_state_machine.c:609 (sofia/default/95878797@127.0.0.1:5060) State EXECUTE going to sleep
  908. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_state_machine.c:543 (sofia/default/95878797@127.0.0.1:5060) Running State Change CS_HANGUP
  909. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_state_machine.c:809 (sofia/default/95878797@127.0.0.1:5060) Callstate Change ACTIVE -> HANGUP
  910. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_state_machine.c:811 (sofia/default/95878797@127.0.0.1:5060) State HANGUP
  911. 2017-05-25 12:54:26.028382 [DEBUG] mod_sofia.c:437 Channel sofia/default/95878797@127.0.0.1:5060 hanging up, cause: NORMAL_CLEARING
  912. 2017-05-25 12:54:26.028382 [DEBUG] mod_sofia.c:490 Sending BYE to sofia/default/95878797@127.0.0.1:5060
  913. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_state_machine.c:60 sofia/default/95878797@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_CLEARING
  914. 2017-05-25 12:54:26.028382 [DEBUG] switch_core_state_machine.c:811 (sofia/default/95878797@127.0.0.1:5060) State HANGUP going to sleep
  915. send 624 bytes to udp/[127.0.0.1]:5060 at 12:54:26.036918:
  916.    ------------------------------------------------------------------------
  917.    BYE sip:95878797@127.0.0.2:36676;transport=UDP SIP/2.0
  918.    Via: SIP/2.0/UDP 127.0.0.3:7777;rport;branch=z9hG4bK2ra6UcXU79aFK
  919.    Route: <sip:127.0.0.1;lr;did=27a.b04c6494>
  920.    Max-Forwards: 70
  921.    From: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  922.    To: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  923.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  924.    CSeq: 107518766 BYE
  925.    User-Agent: TEST_FAX
  926.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  927.    Supported: timer, path, replaces
  928.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  929.    Content-Length: 0
  930.    
  931.    ------------------------------------------------------------------------
  932. recv 450 bytes from udp/[127.0.0.1]:5060 at 12:54:26.224966:
  933.    ------------------------------------------------------------------------
  934.    SIP/2.0 200 OK
  935.    Via: SIP/2.0/UDP 127.0.0.3:7777;received=127.0.0.3;rport=7777;branch=z9hG4bK2ra6UcXU79aFK
  936.    Contact: <sip:95878797@127.0.0.2:36676;transport=UDP>
  937.    To: <sip:95878797@127.0.0.1:5060;transport=UDP>;tag=a1e83108
  938.    From: <sip:123456789@127.0.0.1:5060;transport=UDP>;tag=gUB5yNB70camp
  939.    Call-ID: MThhMDhmZGZlNTY3MjdjZmRhNTJhOGFkNTE1MzgzZjc.
  940.    CSeq: 107518766 BYE
  941.    User-Agent: Z 3.3.25608 r25552
  942.    Content-Length: 0
  943.    
  944.    ------------------------------------------------------------------------
  945. recv 345 bytes from udp/[127.0.0.1]:7000 at 12:54:26.408789:
  946.    ------------------------------------------------------------------------
  947.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  948.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK7ead.8e302c77.0
  949.    To: sip:127.0.0.3:7777
  950.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-2dbe
  951.    CSeq: 14 OPTIONS
  952.    Call-ID: 2a3b058977bb0bc8-3292@127.0.0.1
  953.    Max-Forwards: 70
  954.    Content-Length: 0
  955.    User-Agent: SIP Proxy
  956.    
  957.    ------------------------------------------------------------------------
  958. send 541 bytes to udp/[127.0.0.1]:7000 at 12:54:26.409107:
  959.    ------------------------------------------------------------------------
  960.    SIP/2.0 200 OK
  961.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK7ead.8e302c77.0
  962.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-2dbe
  963.    To: <sip:127.0.0.3:7777>;tag=Uy1NjZ4D1eZ1N
  964.    Call-ID: 2a3b058977bb0bc8-3292@127.0.0.1
  965.    CSeq: 14 OPTIONS
  966.    Contact: <sip:127.0.0.3:7777>
  967.    User-Agent: TEST_FAX
  968.    Accept: application/sdp
  969.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  970.    Supported: timer, path, replaces
  971.    Allow-Events: talk, hold, conference, refer
  972.    Content-Length: 0
  973.    
  974.    ------------------------------------------------------------------------
  975. 2017-05-25 12:54:27.328401 [DEBUG] switch_core_state_machine.c:742 Hangup Command with Session system(/var/www/html/FAX/scripts/emailfax.sh /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.tif 64 Sip6_tarifa+coste test@test.com /var/www/html/FAX/receive_fax_files/59fe0ca5-958e-4801-9c69-3ef056fb102f-FAX.pdf):
  976.  
  977. SMTP -> FROM SERVER:
  978. 220 smtp.gmail.com ESMTP s5sm6864160wra.60 - gsmtp
  979. SMTP -> FROM SERVER:
  980. 250-smtp.gmail.com at your service, [127.0.0.3]
  981. 250-SIZE 35882577
  982. 250-8BITMIME
  983. 250-AUTH LOGIN PLAIN XOAUTH2 PLAIN-CLIENTTOKEN OAUTHBEARER XOAUTH
  984. 250-ENHANCEDSTATUSCODES
  985. 250-PIPELINING
  986. 250-CHUNKING
  987. 250 SMTPUTF8
  988. SMTP -> FROM SERVER:
  989. 250 2.1.0 OK s5sm6864160wra.60 - gsmtp
  990. SMTP -> FROM SERVER:
  991. 250 2.1.5 OK s5sm6864160wra.60 - gsmtp
  992. SMTP -> FROM SERVER:
  993. 354  Go ahead s5sm6864160wra.60 - gsmtp
  994. SMTP -> FROM SERVER:
  995. 250 2.0.0 OK 1495709667 s5sm6864160wra.60 - gsmtp
  996.  
  997. 2017-05-25 12:54:27.328401 [DEBUG] switch_core_state_machine.c:578 (sofia/default/95878797@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  998. 2017-05-25 12:54:27.328401 [DEBUG] switch_core_state_machine.c:543 (sofia/default/95878797@127.0.0.1:5060) Running State Change CS_REPORTING
  999. 2017-05-25 12:54:27.328401 [DEBUG] switch_core_state_machine.c:897 (sofia/default/95878797@127.0.0.1:5060) State REPORTING
  1000. 2017-05-25 12:54:27.328401 [INFO] mod_json_cdr.c:271 Process [59fe0ca5-958e-4801-9c69-3ef056fb102f.cdr.json]
  1001. recv 345 bytes from udp/[127.0.0.4]:7000 at 12:54:27.412257:
  1002.    ------------------------------------------------------------------------
  1003.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  1004.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK3996.faf8db94.0
  1005.    To: sip:127.0.0.3:7777
  1006.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-776c
  1007.    CSeq: 14 OPTIONS
  1008.    Call-ID: 2a3b058977bb0d92-3332@127.0.0.4
  1009.    Max-Forwards: 70
  1010.    Content-Length: 0
  1011.    User-Agent: SIP Proxy
  1012.    
  1013.    ------------------------------------------------------------------------
  1014. send 565 bytes to udp/[127.0.0.4]:7000 at 12:54:27.412519:
  1015.    ------------------------------------------------------------------------
  1016.    SIP/2.0 200 OK
  1017.    Via: SIP/2.0/UDP 127.0.0.1:7000;branch=z9hG4bK3996.faf8db94.0;received=127.0.0.4
  1018.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-776c
  1019.    To: <sip:127.0.0.3:7777>;tag=v7temtNHyQNmH
  1020.    Call-ID: 2a3b058977bb0d92-3332@127.0.0.4
  1021.    CSeq: 14 OPTIONS
  1022.    Contact: <sip:127.0.0.3:7777>
  1023.    User-Agent: TEST_FAX
  1024.    Accept: application/sdp
  1025.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  1026.    Supported: timer, path, replaces
  1027.    Allow-Events: talk, hold, conference, refer
  1028.    Content-Length: 0
  1029.    
  1030.    ------------------------------------------------------------------------
  1031. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_state_machine.c:174 sofia/default/95878797@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_CLEARING
  1032. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_state_machine.c:897 (sofia/default/95878797@127.0.0.1:5060) State REPORTING going to sleep
  1033. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_state_machine.c:569 (sofia/default/95878797@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  1034. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_session.c:1646 Session 5 (sofia/default/95878797@127.0.0.1:5060) Locked, Waiting on external entities
  1035. 2017-05-25 12:54:27.468387 [NOTICE] switch_core_session.c:1664 Session 5 (sofia/default/95878797@127.0.0.1:5060) Ended
  1036. 2017-05-25 12:54:27.468387 [NOTICE] switch_core_session.c:1668 Close Channel sofia/default/95878797@127.0.0.1:5060 [CS_DESTROY]
  1037. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_state_machine.c:700 (sofia/default/95878797@127.0.0.1:5060) Running State Change CS_DESTROY
  1038. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_state_machine.c:710 (sofia/default/95878797@127.0.0.1:5060) State DESTROY
  1039. 2017-05-25 12:54:27.468387 [DEBUG] mod_sofia.c:342 sofia/default/95878797@127.0.0.1:5060 SOFIA DESTROY
  1040. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_state_machine.c:181 sofia/default/95878797@127.0.0.1:5060 Standard DESTROY
  1041. 2017-05-25 12:54:27.468387 [DEBUG] switch_core_state_machine.c:710 (sofia/default/95878797@127.0.0.1:5060) State DESTROY going to sleep
  1042. 2017-05-25 12:54:27.548389 [DEBUG] switch_scheduler.c:144 Deleting task 7 switch_ivr_schedule_hangup (59fe0ca5-958e-4801-9c69-3ef056fb102f)
  1043. recv 338 bytes from udp/[127.0.0.4]:5060 at 12:54:28.415321:
  1044.    ------------------------------------------------------------------------
  1045.    OPTIONS sip:127.0.0.3:7777 SIP/2.0
  1046.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK2add.abfe7083.0
  1047.    To: sip:127.0.0.3:7777
  1048.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-5f6c
  1049.    CSeq: 14 OPTIONS
  1050.    Call-ID: 2a3b058977bb0cd0-3342@0.0.0.0
  1051.    Max-Forwards: 70
  1052.    Content-Length: 0
  1053.    User-Agent: SIP Proxy
  1054.    
  1055.    ------------------------------------------------------------------------
  1056. send 558 bytes to udp/[127.0.0.4]:5060 at 12:54:28.415600:
  1057.    ------------------------------------------------------------------------
  1058.    SIP/2.0 200 OK
  1059.    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK2add.abfe7083.0;received=127.0.0.4
  1060.    From: <sip:ping@127.0.0.1:5060>;tag=df56c3a3d84e6efb9ab79966da869d5e-5f6c
  1061.    To: <sip:127.0.0.3:7777>;tag=Xgm7NN6mU0B7c
  1062.    Call-ID: 2a3b058977bb0cd0-3342@0.0.0.0
  1063.    CSeq: 14 OPTIONS
  1064.    Contact: <sip:127.0.0.3:7777>
  1065.    User-Agent: TEST_FAX
  1066.    Accept: application/sdp
  1067.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY
  1068.    Supported: timer, path, replaces
  1069.    Allow-Events: talk, hold, conference, refer
  1070.    Content-Length: 0
  1071.    
  1072.    ------------------------------------------------------------------------
  1073.  

Replies to Receive Fax not working rss

Title Name Language When
Re: Receive Fax not working LOLOLOL sql 3 Years ago.
Re: Receive Fax not working Queen Human sql 3 Years ago.
Re: Receive Fax not working Emerald Teal sql 3 Years ago.