From Eratic Wolf, 6 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 2017-07-18 12:35:01.588828 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1001@10.254.1.77 [b363bd20-ffc0-4ef0-b1ad-d2f3ff410ccc]
  2. 2017-07-18 12:35:01.588828 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.254.1.77) Running State Change CS_NEW (Cur 1 Tot 9)
  3. 2017-07-18 12:35:01.588828 [DEBUG] sofia.c:10067 sofia/internal/1001@10.254.1.77 receiving invite from 154.118.255.150:5061 version: 1.9.0 -1672-313ddc3 64bit
  4. 2017-07-18 12:35:01.588828 [DEBUG] sofia.c:10238 IP 154.118.255.150 Rejected by acl "domains". Falling back to Digest auth.
  5. 2017-07-18 12:35:01.588828 [DEBUG] sofia.c:2405 detaching session b363bd20-ffc0-4ef0-b1ad-d2f3ff410ccc
  6. 2017-07-18 12:35:01.588828 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1001@10.254.1.77) State NEW
  7. 2017-07-18 12:35:01.608828 [DEBUG] sofia.c:2514 Re-attaching to session b363bd20-ffc0-4ef0-b1ad-d2f3ff410ccc
  8. 2017-07-18 12:35:01.628828 [DEBUG] sofia.c:10067 sofia/internal/1001@10.254.1.77 receiving invite from 154.118.255.150:5061 version: 1.9.0 -1672-313ddc3 64bit
  9. 2017-07-18 12:35:01.628828 [DEBUG] sofia.c:10238 IP 154.118.255.150 Rejected by acl "domains". Falling back to Digest auth.
  10. 2017-07-18 12:35:01.628828 [DEBUG] sofia.c:7283 Channel sofia/internal/1001@10.254.1.77 entering state [received][100]
  11. 2017-07-18 12:35:01.628828 [DEBUG] sofia.c:7293 Remote SDP:
  12. v=0
  13. o=- 174487 174487 IN IP4 154.118.255.150
  14. s=-
  15. c=IN IP4 154.118.255.150
  16. t=0 0
  17. m=audio 16388 RTP/AVP 18 0 2 8 101
  18. a=rtpmap:18 G729/8000
  19. a=rtpmap:0 PCMU/8000
  20. a=rtpmap:2 G726-32/8000
  21. a=rtpmap:8 PCMA/8000
  22. a=rtpmap:101 telephone-event/8000
  23. a=fmtp:101 0-15
  24. a=ptime:20
  25.  
  26. 2017-07-18 12:35:01.628828 [DEBUG] sofia.c:7685 (sofia/internal/1001@10.254.1.77) State Change CS_NEW -> CS_INIT
  27. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.254.1.77) Running State Change CS_INIT (Cur 1 Tot 9)
  28. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@10.254.1.77) State INIT
  29. 2017-07-18 12:35:01.628828 [DEBUG] mod_sofia.c:93 sofia/internal/1001@10.254.1.77 SOFIA INIT
  30. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@10.254.1.77 Standard INIT
  31. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@10.254.1.77) State Change CS_INIT -> CS_ROUTING
  32. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@10.254.1.77) State INIT going to sleep
  33. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.254.1.77) Running State Change CS_ROUTING (Cur 1 Tot 9)
  34. 2017-07-18 12:35:01.628828 [DEBUG] switch_channel.c:2249 (sofia/internal/1001@10.254.1.77) Callstate Change DOWN -> RINGING
  35. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@10.254.1.77) State ROUTING
  36. 2017-07-18 12:35:01.628828 [DEBUG] mod_sofia.c:154 sofia/internal/1001@10.254.1.77 SOFIA ROUTING
  37. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1001@10.254.1.77 Standard ROUTING
  38. 2017-07-18 12:35:01.628828 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->0867251709 in context default
  39. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->unloop] continue=false
  40. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  41. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  42. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->tod_example] continue=true
  43. Dialplan: sofia/internal/1001@10.254.1.77 Date/Time Match (PASS) [tod_example] break=on-false
  44. Dialplan: sofia/internal/1001@10.254.1.77 Action set(open=true)
  45. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->holiday_example] continue=true
  46. Dialplan: sofia/internal/1001@10.254.1.77 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  47. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->global-intercept] continue=false
  48. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [global-intercept] destination_number(0867251709) =~ /^886$/ break=on-false
  49. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->group-intercept] continue=false
  50. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [group-intercept] destination_number(0867251709) =~ /^\*8$/ break=on-false
  51. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->intercept-ext] continue=false
  52. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [intercept-ext] destination_number(0867251709) =~ /^\*\*(\d+)$/ break=on-false
  53. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->redial] continue=false
  54. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [redial] destination_number(0867251709) =~ /^(redial|870)$/ break=on-false
  55. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->global] continue=true
  56. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  57. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [global] ${default_password}(Solidus!@#) =~ /^1234$/ break=never
  58. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  59. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  60. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  61. o=- 174487 174487 IN IP4 154.118.255.150
  62. s=-
  63. c=IN IP4 154.118.255.150
  64. t=0 0
  65. m=audio 16388 RTP/AVP 18 0 2 8 101
  66. a=rtpmap:18 G729/8000
  67. a=rtpmap:0 PCMU/8000
  68. a=rtpmap:2 G726-32/8000
  69. a=rtpmap:8 PCMA/8000
  70. a=rtpmap:101 telephone-event/8000
  71. a=fmtp:101 0-15
  72. a=ptime:20
  73. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  74. Dialplan: sofia/internal/1001@10.254.1.77 Absolute Condition [global]
  75. Dialplan: sofia/internal/1001@10.254.1.77 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  76. Dialplan: sofia/internal/1001@10.254.1.77 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  77. Dialplan: sofia/internal/1001@10.254.1.77 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  78. Dialplan: sofia/internal/1001@10.254.1.77 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  79. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->snom-demo-2] continue=false
  80. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [snom-demo-2] destination_number(0867251709) =~ /^9001$/ break=on-false
  81. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->snom-demo-1] continue=false
  82. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [snom-demo-1] destination_number(0867251709) =~ /^9000$/ break=on-false
  83. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->eavesdrop] continue=false
  84. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [eavesdrop] destination_number(0867251709) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  85. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->eavesdrop] continue=false
  86. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [eavesdrop] destination_number(0867251709) =~ /^779$/ break=on-false
  87. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->call_return] continue=false
  88. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [call_return] destination_number(0867251709) =~ /^\*69$|^869$|^lcr$/ break=on-false
  89. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->del-group] continue=false
  90. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [del-group] destination_number(0867251709) =~ /^80(\d{2})$/ break=on-false
  91. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->add-group] continue=false
  92. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [add-group] destination_number(0867251709) =~ /^81(\d{2})$/ break=on-false
  93. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->call-group-simo] continue=false
  94. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [call-group-simo] destination_number(0867251709) =~ /^82(\d{2})$/ break=on-false
  95. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->call-group-order] continue=false
  96. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [call-group-order] destination_number(0867251709) =~ /^83(\d{2})$/ break=on-false
  97. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->extension-intercom] continue=false
  98. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [extension-intercom] destination_number(0867251709) =~ /^8(10[01][0-9])$/ break=on-false
  99. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->Local_Extension] continue=false
  100. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [Local_Extension] destination_number(0867251709) =~ /^(10[01][0-9])$/ break=on-false
  101. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->Local_Extension_Skinny] continue=false
  102. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [Local_Extension_Skinny] destination_number(0867251709) =~ /^(11[01][0-9])$/ break=on-false
  103. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->group_dial_sales] continue=false
  104. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [group_dial_sales] destination_number(0867251709) =~ /^2000$/ break=on-false
  105. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->group_dial_support] continue=false
  106. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [group_dial_support] destination_number(0867251709) =~ /^2001$/ break=on-false
  107. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->group_dial_billing] continue=false
  108. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [group_dial_billing] destination_number(0867251709) =~ /^2002$/ break=on-false
  109. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->operator] continue=false
  110. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [operator] destination_number(0867251709) =~ /^(operator|0)$/ break=on-false
  111. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->vmain] continue=false
  112. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [vmain] destination_number(0867251709) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  113. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->sip_uri] continue=false
  114. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [sip_uri] destination_number(0867251709) =~ /^sip:(.*)$/ break=on-false
  115. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->nb_conferences] continue=false
  116. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [nb_conferences] destination_number(0867251709) =~ /^(30\d{2})$/ break=on-false
  117. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->wb_conferences] continue=false
  118. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [wb_conferences] destination_number(0867251709) =~ /^(31\d{2})$/ break=on-false
  119. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->uwb_conferences] continue=false
  120. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [uwb_conferences] destination_number(0867251709) =~ /^(32\d{2})$/ break=on-false
  121. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->cdquality_conferences] continue=false
  122. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [cdquality_conferences] destination_number(0867251709) =~ /^(33\d{2})$/ break=on-false
  123. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->cdquality_stereo_conferences] continue=false
  124. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [cdquality_stereo_conferences] destination_number(0867251709) =~ /^(35\d{2}).*?-screen$/ break=on-false
  125. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->conference-canvases] continue=true
  126. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [conference-canvases] destination_number(0867251709) =~ /(35\d{2})-canvas-(\d+)/ break=on-false
  127. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->conf mod] continue=false
  128. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [conf mod] destination_number(0867251709) =~ /^6070-moderator$/ break=on-false
  129. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->cdquality_conferences] continue=false
  130. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [cdquality_conferences] destination_number(0867251709) =~ /^(35\d{2})$/ break=on-false
  131. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->freeswitch_public_conf_via_sip] continue=false
  132. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(0867251709) =~ /^9(888|8888|1616|3232)$/ break=on-false
  133. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->mad_boss_intercom] continue=false
  134. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [mad_boss_intercom] destination_number(0867251709) =~ /^0911$/ break=on-false
  135. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->mad_boss_intercom] continue=false
  136. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [mad_boss_intercom] destination_number(0867251709) =~ /^0912$/ break=on-false
  137. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->mad_boss] continue=false
  138. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [mad_boss] destination_number(0867251709) =~ /^0913$/ break=on-false
  139. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->ivr_demo] continue=false
  140. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [ivr_demo] destination_number(0867251709) =~ /^5000$/ break=on-false
  141. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->dynamic_conference] continue=false
  142. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [dynamic_conference] destination_number(0867251709) =~ /^5001$/ break=on-false
  143. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->rtp_multicast_page] continue=false
  144. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [rtp_multicast_page] destination_number(0867251709) =~ /^pagegroup$|^7243$/ break=on-false
  145. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->park] continue=false
  146. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [park] destination_number(0867251709) =~ /^5900$/ break=on-false
  147. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->unpark] continue=false
  148. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [unpark] destination_number(0867251709) =~ /^5901$/ break=on-false
  149. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->valet_park] continue=false
  150. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [valet_park] destination_number(0867251709) =~ /^(6000)$/ break=on-false
  151. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->valet_park] continue=false
  152. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [valet_park] destination_number(0867251709) =~ /^((?!6000)60\d{2})$/ break=on-false
  153. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->park] continue=false
  154. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
  155. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [park] destination_number(0867251709) =~ /park\+(\d+)/ break=on-false
  156. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->unpark] continue=false
  157. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
  158. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [unpark] destination_number(0867251709) =~ /^parking$/ break=on-false
  159. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->park] continue=false
  160. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
  161. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [park] destination_number(0867251709) =~ /callpark/ break=on-false
  162. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->unpark] continue=false
  163. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
  164. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [unpark] destination_number(0867251709) =~ /pickup/ break=on-false
  165. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->wait] continue=false
  166. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [wait] destination_number(0867251709) =~ /^wait$/ break=on-false
  167. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->fax_receive] continue=false
  168. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [fax_receive] destination_number(0867251709) =~ /^9178$/ break=on-false
  169. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->fax_transmit] continue=false
  170. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [fax_transmit] destination_number(0867251709) =~ /^9179$/ break=on-false
  171. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->ringback_180] continue=false
  172. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [ringback_180] destination_number(0867251709) =~ /^9180$/ break=on-false
  173. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->ringback_183_uk_ring] continue=false
  174. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [ringback_183_uk_ring] destination_number(0867251709) =~ /^9181$/ break=on-false
  175. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->ringback_183_music_ring] continue=false
  176. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [ringback_183_music_ring] destination_number(0867251709) =~ /^9182$/ break=on-false
  177. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->ringback_post_answer_uk_ring] continue=false
  178. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(0867251709) =~ /^9183$/ break=on-false
  179. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->ringback_post_answer_music] continue=false
  180. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [ringback_post_answer_music] destination_number(0867251709) =~ /^9184$/ break=on-false
  181. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->ClueCon] continue=false
  182. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [ClueCon] destination_number(0867251709) =~ /^9191$/ break=on-false
  183. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->show_info] continue=false
  184. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [show_info] destination_number(0867251709) =~ /^9192$/ break=on-false
  185. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->video_record] continue=false
  186. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [video_record] destination_number(0867251709) =~ /^9193$/ break=on-false
  187. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->video_playback] continue=false
  188. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [video_playback] destination_number(0867251709) =~ /^9194$/ break=on-false
  189. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->delay_echo] continue=false
  190. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [delay_echo] destination_number(0867251709) =~ /^9195$/ break=on-false
  191. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->echo] continue=false
  192. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [echo] destination_number(0867251709) =~ /^9196$/ break=on-false
  193. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->milliwatt] continue=false
  194. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [milliwatt] destination_number(0867251709) =~ /^9197$/ break=on-false
  195. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->tone_stream] continue=false
  196. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [tone_stream] destination_number(0867251709) =~ /^9198$/ break=on-false
  197. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->zrtp_enrollement] continue=false
  198. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [zrtp_enrollement] destination_number(0867251709) =~ /^9787$/ break=on-false
  199. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->hold_music] continue=false
  200. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [hold_music] destination_number(0867251709) =~ /^9664$/ break=on-false
  201. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->laugh break] continue=false
  202. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [laugh break] destination_number(0867251709) =~ /^9386$/ break=on-false
  203. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->101] continue=false
  204. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [101] destination_number(0867251709) =~ /^101$/ break=on-false
  205. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->pizza_demo] continue=false
  206. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [pizza_demo] destination_number(0867251709) =~ /^(pizza|74992)$/ break=on-false
  207. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->Talking Clock Time] continue=false
  208. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [Talking Clock Time] destination_number(0867251709) =~ /^9170$/ break=on-false
  209. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->Talking Clock Date] continue=false
  210. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [Talking Clock Date] destination_number(0867251709) =~ /^9171$/ break=on-false
  211. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->Talking Clock Date and Time] continue=false
  212. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [Talking Clock Date and Time] destination_number(0867251709) =~ /^9172$/ break=on-false
  213. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->local.example.com] continue=false
  214. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [local.example.com] ${toll_allow}(domestic,international,local) =~ /local/ break=on-false
  215. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [local.example.com] destination_number(0867251709) =~ /^(\d{7})$/ break=on-false
  216. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->domestic.example.com] continue=false
  217. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [domestic.example.com] ${toll_allow}(domestic,international,local) =~ /domestic/ break=on-false
  218. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [domestic.example.com] destination_number(0867251709) =~ /^(\d{11})$/ break=on-false
  219. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->international.example.com] continue=false
  220. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [international.example.com] ${toll_allow}(domestic,international,local) =~ /international/ break=on-false
  221. Dialplan: sofia/internal/1001@10.254.1.77 Regex (FAIL) [international.example.com] destination_number(0867251709) =~ /^(011\d+)$/ break=on-false
  222. Dialplan: sofia/internal/1001@10.254.1.77 parsing [default->10digit] continue=false
  223. Dialplan: sofia/internal/1001@10.254.1.77 Regex (PASS) [10digit] destination_number(0867251709) =~ /^(\d{10})$/ break=on-false
  224. Dialplan: sofia/internal/1001@10.254.1.77 Action set(effective_caller_id_number=${outbound_caller_id_number})
  225. Dialplan: sofia/internal/1001@10.254.1.77 Action set(effective_caller_id_name=${outbound_caller_id_name})
  226. Dialplan: sofia/internal/1001@10.254.1.77 Action export(t38_passthru=true)
  227. Dialplan: sofia/internal/1001@10.254.1.77 Action bridge(sofia/gateway/brollcresfax/0867251709)
  228. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1001@10.254.1.77) State Change CS_ROUTING -> CS_EXECUTE
  229. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@10.254.1.77) State ROUTING going to sleep
  230. 2017-07-18 12:35:01.628828 [CONSOLE] sofia_presence.c:1619 Event Thread Started
  231. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.254.1.77) Running State Change CS_EXECUTE (Cur 1 Tot 9)
  232. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1001@10.254.1.77) State EXECUTE
  233. 2017-07-18 12:35:01.628828 [DEBUG] mod_sofia.c:209 sofia/internal/1001@10.254.1.77 SOFIA EXECUTE
  234. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1001@10.254.1.77 Standard EXECUTE
  235. EXECUTE sofia/internal/1001@10.254.1.77 set(open=true)
  236. 2017-07-18 12:35:01.628828 [DEBUG] mod_dptools.c:1588 SET sofia/internal/1001@10.254.1.77 [open]=[true]
  237. EXECUTE sofia/internal/1001@10.254.1.77 hash(insert/10.254.1.77-spymap/1001/b363bd20-ffc0-4ef0-b1ad-d2f3ff410ccc)
  238. EXECUTE sofia/internal/1001@10.254.1.77 hash(insert/10.254.1.77-last_dial/1001/0867251709)
  239. EXECUTE sofia/internal/1001@10.254.1.77 hash(insert/10.254.1.77-last_dial/global/b363bd20-ffc0-4ef0-b1ad-d2f3ff410ccc)
  240. EXECUTE sofia/internal/1001@10.254.1.77 export(RFC2822_DATE=Tue, 18 Jul 2017 12:35:01 +0200)
  241. 2017-07-18 12:35:01.628828 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Tue, 18 Jul 2017 12:35:01 +0200]
  242. EXECUTE sofia/internal/1001@10.254.1.77 set(effective_caller_id_number=0000000000)
  243. 2017-07-18 12:35:01.628828 [DEBUG] mod_dptools.c:1588 SET sofia/internal/1001@10.254.1.77 [effective_caller_id_number]=[0000000000]
  244. EXECUTE sofia/internal/1001@10.254.1.77 set(effective_caller_id_name=FreeSWITCH)
  245. 2017-07-18 12:35:01.628828 [DEBUG] mod_dptools.c:1588 SET sofia/internal/1001@10.254.1.77 [effective_caller_id_name]=[FreeSWITCH]
  246. EXECUTE sofia/internal/1001@10.254.1.77 export(t38_passthru=true)
  247. 2017-07-18 12:35:01.628828 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [t38_passthru]=[true]
  248. EXECUTE sofia/internal/1001@10.254.1.77 bridge(sofia/gateway/brollcresfax/0867251709)
  249. 2017-07-18 12:35:01.628828 [DEBUG] switch_channel.c:1250 sofia/internal/1001@10.254.1.77 EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 18 Jul 2017 12:35:01 +0200] to event
  250. 2017-07-18 12:35:01.628828 [DEBUG] switch_channel.c:1250 sofia/internal/1001@10.254.1.77 EXPORTING[export_vars] [t38_passthru]=[true] to event
  251. 2017-07-18 12:35:01.628828 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables
  252. 2017-07-18 12:35:01.628828 [NOTICE] switch_channel.c:1104 New Channel sofia/external/0867251709 [28aa2b06-b5e7-480d-b91f-4c0ed9838463]
  253. 2017-07-18 12:35:01.628828 [DEBUG] mod_sofia.c:5026 (sofia/external/0867251709) State Change CS_NEW -> CS_INIT
  254. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0867251709) Running State Change CS_INIT (Cur 2 Tot 10)
  255. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:627 (sofia/external/0867251709) State INIT
  256. 2017-07-18 12:35:01.628828 [DEBUG] mod_sofia.c:93 sofia/external/0867251709 SOFIA INIT
  257. 2017-07-18 12:35:01.628828 [DEBUG] sofia_glue.c:1298 sofia/external/0867251709 sending invite version: 1.9.0 -1672-313ddc3 64bit
  258. Local SDP:
  259. v=0
  260. o=FreeSWITCH 1500341821 1500341822 IN IP4 10.254.1.77
  261. s=FreeSWITCH
  262. c=IN IP4 10.254.1.77
  263. t=0 0
  264. m=audio 32280 RTP/AVP 18 101
  265. a=rtpmap:18 G729/8000
  266. a=rtpmap:101 telephone-event/8000
  267. a=fmtp:101 0-16
  268. a=ptime:20
  269. a=sendrecv
  270.  
  271. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:40 sofia/external/0867251709 Standard INIT
  272. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:48 (sofia/external/0867251709) State Change CS_INIT -> CS_ROUTING
  273. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:627 (sofia/external/0867251709) State INIT going to sleep
  274. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0867251709) Running State Change CS_ROUTING (Cur 2 Tot 10)
  275. 2017-07-18 12:35:01.628828 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [calling][0]
  276. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:643 (sofia/external/0867251709) State ROUTING
  277. 2017-07-18 12:35:01.628828 [DEBUG] mod_sofia.c:154 sofia/external/0867251709 SOFIA ROUTING
  278. 2017-07-18 12:35:01.628828 [DEBUG] switch_ivr_originate.c:67 (sofia/external/0867251709) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  279. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:643 (sofia/external/0867251709) State ROUTING going to sleep
  280. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0867251709) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 10)
  281. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:662 (sofia/external/0867251709) State CONSUME_MEDIA
  282. 2017-07-18 12:35:01.628828 [DEBUG] switch_core_state_machine.c:662 (sofia/external/0867251709) State CONSUME_MEDIA going to sleep
  283. 2017-07-18 12:35:01.628828 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [calling][0]
  284. 2017-07-18 12:35:03.388826 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [proceeding][180]
  285. 2017-07-18 12:35:03.388826 [NOTICE] sofia.c:7391 Ring-Ready sofia/external/0867251709!
  286. 2017-07-18 12:35:03.388826 [DEBUG] switch_channel.c:3354 (sofia/external/0867251709) Callstate Change DOWN -> RINGING
  287. 2017-07-18 12:35:03.388826 [NOTICE] mod_sofia.c:2417 Ring-Ready sofia/internal/1001@10.254.1.77!
  288. 2017-07-18 12:35:03.388826 [DEBUG] sofia.c:7283 Channel sofia/internal/1001@10.254.1.77 entering state [early][180]
  289. 2017-07-18 12:35:03.388826 [NOTICE] switch_ivr_originate.c:527 Ring Ready sofia/internal/1001@10.254.1.77!
  290. 2017-07-18 12:35:04.108825 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [completing][200]
  291. 2017-07-18 12:35:04.108825 [DEBUG] sofia.c:7293 Remote SDP:
  292. v=0
  293. o=CvtVoice-1 995866939 995866939 IN IP4 154.118.252.43
  294. s=CvtVoice-1
  295. c=IN IP4 154.118.252.43
  296. t=0 0
  297. m=audio 14678 RTP/AVP 18 101
  298. a=rtpmap:18 G729/8000
  299. a=fmtp:18 annexb=no
  300. a=rtpmap:101 telephone-event/8000
  301. a=fmtp:101 0-16
  302. a=ptime:20
  303. a=maxptime:230
  304.  
  305. 2017-07-18 12:35:04.108825 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [ready][200]
  306. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:5115 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  307. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:5170 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  308. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:5031 Set telephone-event payload to 101@8000
  309. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:3430 Set Codec sofia/external/0867251709 G729/8000 20 ms 160 samples 8000 bits 1 channels
  310. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_codec.c:111 sofia/external/0867251709 Original read codec set to G729:18
  311. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
  312. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:5432 sofia/external/0867251709 Set 2833 dtmf send payload to 101 recv payload to 101
  313. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:8149 AUDIO RTP [sofia/external/0867251709] 10.254.1.77 port 32280 -> 154.118.252.43 port 14678 codec: 18 ms: 20
  314. 2017-07-18 12:35:04.108825 [DEBUG] switch_rtp.c:4164 Starting timer [soft] 160 bytes per 20ms
  315. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:8452 sofia/external/0867251709 Set 2833 dtmf send payload to 101
  316. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:8459 sofia/external/0867251709 Set 2833 dtmf receive payload to 101
  317. 2017-07-18 12:35:04.108825 [DEBUG] switch_core_media.c:8482 sofia/external/0867251709 Set rtp dtmf delay to 40
  318. 2017-07-18 12:35:04.108825 [NOTICE] sofia.c:8419 Channel [sofia/external/0867251709] has been answered
  319. 2017-07-18 12:35:04.108825 [DEBUG] switch_channel.c:3781 (sofia/external/0867251709) Callstate Change RINGING -> ACTIVE
  320. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5115 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  321. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5170 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  322. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5115 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  323. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5115 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G729:18:8000:20:8000:1]
  324. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5115 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  325. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5031 Set telephone-event payload to 101@8000
  326. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:3430 Set Codec sofia/internal/1001@10.254.1.77 G729/8000 20 ms 160 samples 8000 bits 1 channels
  327. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@10.254.1.77 Original read codec set to G729:18
  328. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
  329. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:5432 sofia/internal/1001@10.254.1.77 Set 2833 dtmf send payload to 101 recv payload to 101
  330. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:8149 AUDIO RTP [sofia/internal/1001@10.254.1.77] 10.254.1.77 port 28044 -> 154.118.255.150 port 16388 codec: 18 ms: 20
  331. 2017-07-18 12:35:04.128825 [DEBUG] switch_rtp.c:4164 Starting timer [soft] 160 bytes per 20ms
  332. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:8452 sofia/internal/1001@10.254.1.77 Set 2833 dtmf send payload to 101
  333. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:8459 sofia/internal/1001@10.254.1.77 Set 2833 dtmf receive payload to 101
  334. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:8482 sofia/internal/1001@10.254.1.77 Set rtp dtmf delay to 40
  335. 2017-07-18 12:35:04.128825 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@10.254.1.77!
  336. 2017-07-18 12:35:04.128825 [DEBUG] switch_channel.c:3482 (sofia/internal/1001@10.254.1.77) Callstate Change RINGING -> EARLY
  337. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_media.c:8132 Audio params are unchanged for sofia/internal/1001@10.254.1.77.
  338. 2017-07-18 12:35:04.128825 [DEBUG] mod_sofia.c:881 Local SDP sofia/internal/1001@10.254.1.77:
  339. v=0
  340. o=FreeSWITCH 1500346060 1500346061 IN IP4 10.254.1.77
  341. s=FreeSWITCH
  342. c=IN IP4 10.254.1.77
  343. t=0 0
  344. m=audio 28044 RTP/AVP 18 101
  345. a=rtpmap:18 G729/8000
  346. a=rtpmap:101 telephone-event/8000
  347. a=fmtp:101 0-16
  348. a=ptime:20
  349. a=sendrecv
  350.  
  351. 2017-07-18 12:35:04.128825 [DEBUG] sofia.c:7283 Channel sofia/internal/1001@10.254.1.77 entering state [completed][200]
  352. 2017-07-18 12:35:04.128825 [NOTICE] switch_ivr_originate.c:3652 Channel [sofia/internal/1001@10.254.1.77] has been answered
  353. 2017-07-18 12:35:04.128825 [DEBUG] switch_channel.c:3781 (sofia/internal/1001@10.254.1.77) Callstate Change EARLY -> ACTIVE
  354. 2017-07-18 12:35:04.128825 [DEBUG] switch_ivr_originate.c:3710 Originate Resulted in Success: [sofia/external/0867251709]
  355. 2017-07-18 12:35:04.128825 [DEBUG] switch_ivr_bridge.c:1744 (sofia/external/0867251709) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  356. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0867251709) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 10)
  357. 2017-07-18 12:35:04.128825 [DEBUG] switch_core_state_machine.c:653 (sofia/external/0867251709) State EXCHANGE_MEDIA
  358. 2017-07-18 12:35:04.128825 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
  359. 2017-07-18 12:35:04.168827 [DEBUG] switch_rtp.c:7338 Correct audio ip/port confirmed.
  360. 2017-07-18 12:35:04.208827 [DEBUG] switch_rtp.c:7338 Correct audio ip/port confirmed.
  361. 2017-07-18 12:35:04.228826 [DEBUG] sofia.c:7283 Channel sofia/internal/1001@10.254.1.77 entering state [ready][200]
  362. 2017-07-18 12:35:04.588825 [DEBUG] sofia.c:7283 Channel sofia/internal/1001@10.254.1.77 entering state [received][100]
  363. 2017-07-18 12:35:04.588825 [DEBUG] sofia.c:7293 Remote SDP:
  364. v=0
  365. o=- 174487 174488 IN IP4 154.118.255.150
  366. s=-
  367. c=IN IP4 154.118.255.150
  368. t=0 0
  369. m=image 16388 udptl t38
  370. a=T38FaxVersion:0
  371. a=T38MaxBitRate:14400
  372. a=T38FaxRateManagement:transferredTCF
  373. a=T38FaxMaxBuffer:200
  374. a=T38FaxMaxDatagram:200
  375. a=T38FaxUdpEC:t38UDPRedundancy
  376.  
  377. 2017-07-18 12:35:04.588825 [DEBUG] switch_core_media.c:4698 sofia/internal/1001@10.254.1.77 T38 REFUSE on request
  378. 2017-07-18 12:35:04.588825 [DEBUG] sofia.c:8206 Reinvite resulted in codec negotiation failure.
  379. 2017-07-18 12:35:04.588825 [DEBUG] sofia.c:7276 Channel sofia/internal/1001@10.254.1.77 skipping state [ready][488]
  380. 2017-07-18 12:35:04.648826 [DEBUG] sofia.c:7283 Channel sofia/internal/1001@10.254.1.77 entering state [received][100]
  381. 2017-07-18 12:35:04.648826 [DEBUG] sofia.c:7293 Remote SDP:
  382. v=0
  383. o=- 174487 174489 IN IP4 154.118.255.150
  384. s=-
  385. c=IN IP4 154.118.255.150
  386. t=0 0
  387. m=audio 16388 RTP/AVP 8
  388. a=rtpmap:8 PCMA/8000
  389. a=ptime:20
  390. a=fax
  391.  
  392. 2017-07-18 12:35:04.648826 [DEBUG] switch_core_media.c:5115 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  393. 2017-07-18 12:35:04.648826 [DEBUG] switch_core_media.c:5403 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  394. 2017-07-18 12:35:04.648826 [DEBUG] switch_core_media.c:5432 sofia/internal/1001@10.254.1.77 Set 2833 dtmf send payload to 101 recv payload to 101
  395. 2017-07-18 12:35:04.648826 [DEBUG] sofia.c:8206 Reinvite resulted in codec negotiation failure.
  396. 2017-07-18 12:35:04.648826 [DEBUG] sofia.c:7276 Channel sofia/internal/1001@10.254.1.77 skipping state [ready][488]
  397. 2017-07-18 12:35:17.208827 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [received][100]
  398. 2017-07-18 12:35:17.208827 [DEBUG] sofia.c:7293 Remote SDP:
  399. v=0
  400. o=CvtVoice-1 995866939 995866940 IN IP4 154.118.252.43
  401. s=CvtVoice-1
  402. c=IN IP4 154.118.252.43
  403. t=0 0
  404. m=image 12484 udptl t38
  405. a=T38FaxVersion:0
  406. a=T38MaxBitRate:14400
  407. a=T38FaxRateManagement:transferredTCF
  408. a=T38FaxMaxDatagram:397
  409. a=T38FaxUdpEC:t38UDPFEC
  410.  
  411. 2017-07-18 12:35:17.208827 [DEBUG] switch_core_media.c:4698 sofia/external/0867251709 T38 REFUSE on request
  412. 2017-07-18 12:35:17.208827 [DEBUG] sofia.c:8206 Reinvite resulted in codec negotiation failure.
  413. 2017-07-18 12:35:17.208827 [DEBUG] sofia.c:7276 Channel sofia/external/0867251709 skipping state [ready][488]
  414. 2017-07-18 12:35:17.228852 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [received][100]
  415. 2017-07-18 12:35:17.228852 [DEBUG] sofia.c:7293 Remote SDP:
  416. v=0
  417. o=CvtVoice-1 995866939 995866941 IN IP4 154.118.252.43
  418. s=CvtVoice-1
  419. c=IN IP4 154.118.252.43
  420. t=0 0
  421. m=audio 14678 RTP/AVP 18 101
  422. a=rtpmap:18 G729/8000
  423. a=fmtp:18 annexb=no
  424. a=rtpmap:101 telephone-event/8000
  425. a=fmtp:101 0-16
  426. a=ptime:20
  427. a=maxptime:230
  428.  
  429. 2017-07-18 12:35:17.228852 [DEBUG] switch_core_media.c:5115 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  430. 2017-07-18 12:35:17.228852 [DEBUG] switch_core_media.c:5170 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  431. 2017-07-18 12:35:17.228852 [DEBUG] switch_core_media.c:5031 Set telephone-event payload to 101@8000
  432. 2017-07-18 12:35:17.228852 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
  433. 2017-07-18 12:35:17.228852 [DEBUG] switch_core_media.c:5432 sofia/external/0867251709 Set 2833 dtmf send payload to 101 recv payload to 101
  434. 2017-07-18 12:35:17.228852 [DEBUG] switch_core_media.c:8132 Audio params are unchanged for sofia/external/0867251709.
  435. 2017-07-18 12:35:17.228852 [DEBUG] sofia.c:8198 Processing updated SDP
  436. 2017-07-18 12:35:17.228852 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [completed][200]
  437. 2017-07-18 12:35:17.248826 [DEBUG] sofia.c:7283 Channel sofia/external/0867251709 entering state [ready][200]
  438. 2017-07-18 12:35:17.308827 [NOTICE] sofia.c:1079 Hangup sofia/external/0867251709 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  439. 2017-07-18 12:35:17.308827 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/external/0867251709]
  440. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:653 (sofia/external/0867251709) State EXCHANGE_MEDIA going to sleep
  441. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0867251709) Running State Change CS_HANGUP (Cur 2 Tot 10)
  442. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:850 (sofia/external/0867251709) Callstate Change ACTIVE -> HANGUP
  443. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:852 (sofia/external/0867251709) State HANGUP
  444. 2017-07-18 12:35:17.308827 [DEBUG] mod_sofia.c:449 Channel sofia/external/0867251709 hanging up, cause: NORMAL_CLEARING
  445. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:60 sofia/external/0867251709 Standard HANGUP, cause: NORMAL_CLEARING
  446. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:852 (sofia/external/0867251709) State HANGUP going to sleep
  447. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:619 (sofia/external/0867251709) State Change CS_HANGUP -> CS_REPORTING
  448. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0867251709) Running State Change CS_REPORTING (Cur 2 Tot 10)
  449. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:938 (sofia/external/0867251709) State REPORTING
  450. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:174 sofia/external/0867251709 Standard REPORTING, cause: NORMAL_CLEARING
  451. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:938 (sofia/external/0867251709) State REPORTING going to sleep
  452. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_state_machine.c:610 (sofia/external/0867251709) State Change CS_REPORTING -> CS_DESTROY
  453. 2017-07-18 12:35:17.308827 [DEBUG] switch_core_session.c:1713 Session 10 (sofia/external/0867251709) Locked, Waiting on external entities