From aa, 5 Years ago, written in Plain Text.
- view diff
Embed
  1.  
  2.  
  3. [This app Best viewed at 160x60 or more..]
  4. +OK log level  [7]
  5. 2018-08-29 13:24:48.295933 [NOTICE] switch_channel.c:1104 New Channel sofia/external/5195752944@208.89.129.46 [be373d2a-b115-4ab2-8037-5a7b62a392cc]
  6. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_NEW (Cur 1 Tot 46967)
  7. 2018-08-29 13:24:48.295933 [DEBUG] sofia.c:9825 sofia/external/5195752944@208.89.129.46 receiving invite from 208.89.129.46:5060 version: 1.6.16  64bit
  8. 2018-08-29 13:24:48.295933 [DEBUG] sofia.c:7048 Channel sofia/external/5195752944@208.89.129.46 entering state [received][100]
  9. 2018-08-29 13:24:48.295933 [DEBUG] sofia.c:7058 Remote SDP:
  10. v=0
  11. o=IRISMSC8 2260163627 2260163627 IN IP4 208.89.129.46
  12. s=sip call
  13. c=IN IP4 208.89.128.69
  14. t=0 0
  15. m=audio 59728 RTP/AVP 0 8 18 9 101
  16. a=rtpmap:101 telephone-event/8000
  17. a=ptime:20
  18.  
  19. 2018-08-29 13:24:48.295933 [DEBUG] sofia.c:7450 (sofia/external/5195752944@208.89.129.46) State Change CS_NEW -> CS_INIT
  20. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:603 (sofia/external/5195752944@208.89.129.46) State NEW
  21. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_INIT (Cur 1 Tot 46967)
  22. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5195752944@208.89.129.46) State INIT
  23. 2018-08-29 13:24:48.295933 [DEBUG] mod_sofia.c:90 sofia/external/5195752944@208.89.129.46 SOFIA INIT
  24. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:40 sofia/external/5195752944@208.89.129.46 Standard INIT
  25. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:48 (sofia/external/5195752944@208.89.129.46) State Change CS_INIT -> CS_ROUTING
  26. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5195752944@208.89.129.46) State INIT going to sleep
  27. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_ROUTING (Cur 1 Tot 46967)
  28. 2018-08-29 13:24:48.295933 [DEBUG] switch_channel.c:2249 (sofia/external/5195752944@208.89.129.46) Callstate Change DOWN -> RINGING
  29. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING
  30. 2018-08-29 13:24:48.295933 [DEBUG] mod_sofia.c:143 sofia/external/5195752944@208.89.129.46 SOFIA ROUTING
  31. 2018-08-29 13:24:48.295933 [DEBUG] switch_core_state_machine.c:236 sofia/external/5195752944@208.89.129.46 Standard ROUTING
  32. 2018-08-29 13:24:48.295933 [INFO] mod_dialplan_xml.c:637 Processing 5195752944 <5195752944>->15198846700 in context public
  33. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->CNAM] continue=true
  34. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [CNAM] context(public) =~ /public/ break=on-false
  35. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [CNAM] destination_number(15198846700) =~ /\d{10,}/ break=on-false
  36. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(call_direction=inbound) INLINE
  37. EXECUTE sofia/external/5195752944@208.89.129.46 set(call_direction=inbound)
  38. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [call_direction]=[inbound]
  39. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(domain_name=208.89.129.46) INLINE
  40. EXECUTE sofia/external/5195752944@208.89.129.46 set(domain_name=208.89.129.46)
  41. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [domain_name]=[208.89.129.46]
  42. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(call_direction=inbound)
  43. Dialplan: sofia/external/5195752944@208.89.129.46 Action export(effective_caller_id_name=${cidlookup(${caller_id_number})}) INLINE
  44. EXECUTE sofia/external/5195752944@208.89.129.46 export(effective_caller_id_name=Adrien Alexson)
  45. 2018-08-29 13:24:48.315933 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [effective_caller_id_name]=[Adrien Alexson]
  46. Dialplan: sofia/external/5195752944@208.89.129.46 Action export(caller_id_name=${cidlookup(${caller_id_number})})
  47. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->CNAM] continue=true
  48. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [CNAM] context(public) =~ /public/ break=on-false
  49. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [CNAM] destination_number(15198846700) =~ /(\d{10,})/ break=on-false
  50. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(call_direction=inbound) INLINE
  51. EXECUTE sofia/external/5195752944@208.89.129.46 set(call_direction=inbound)
  52. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [call_direction]=[inbound]
  53. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(domain_name=208.89.129.46) INLINE
  54. EXECUTE sofia/external/5195752944@208.89.129.46 set(domain_name=208.89.129.46)
  55. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [domain_name]=[208.89.129.46]
  56. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(effective_caller_id_name=${cidlookup(${caller_id_number})}) INLINE
  57. EXECUTE sofia/external/5195752944@208.89.129.46 set(effective_caller_id_name=Adrien Alexson)
  58. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [effective_caller_id_name]=[Adrien Alexson]
  59. Dialplan: sofia/external/5195752944@208.89.129.46 Action log(test099-${caller_id_name})
  60. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->16138000576] continue=false
  61. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [16138000576] destination_number(15198846700) =~ /^1?(6138000576)$/ break=on-false
  62. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->2262435236] continue=false
  63. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [2262435236] destination_number(15198846700) =~ /^(2262435236)$/ break=on-false
  64. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->2262435238] continue=false
  65. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [2262435238] destination_number(15198846700) =~ /^(2262435238)$/ break=on-false
  66. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->2266400027] continue=false
  67. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [2266400027] destination_number(15198846700) =~ /^(2266400027)$/ break=on-false
  68. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->7059960303] continue=false
  69. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [7059960303] destination_number(15198846700) =~ /^(7059960303)$/ break=on-false
  70. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->5198846700-Cell] continue=false
  71. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [5198846700-Cell] context(public) =~ /public/ break=on-false
  72. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [5198846700-Cell] caller_id_number(5195752944) =~ /2267920807|2268081547|5198846700/ break=on-false
  73. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->AA-DISA] continue=false
  74. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [AA-DISA] context(public) =~ /public/ break=on-false
  75. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [AA-DISA] caller_id_number(5195752944) =~ /5195752944|2262204796/ break=on-false
  76. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [AA-DISA] destination_number(15198846700) =~ /^\+?1?(4167577930)$/ break=on-false
  77. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->RAJA-DISA] continue=false
  78. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [RAJA-DISA] context(public) =~ /public/ break=on-false
  79. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [RAJA-DISA] caller_id_number(5195752944) =~ /5195019008|4162006746|5193562294|5192910677|5192911777/ break=on-false
  80. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+12262435231] continue=false
  81. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+12262435231] context(public) =~ /public/ break=on-false
  82. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+12262435231] destination_number(15198846700) =~ /^\+?1?(2262435231)$/ break=on-false
  83. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+12262435232] continue=false
  84. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+12262435232] context(public) =~ /public/ break=on-false
  85. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+12262435232] destination_number(15198846700) =~ /^\+?1?(2262435232)$/ break=on-false
  86. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+12264764502] continue=false
  87. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+12264764502] context(public) =~ /public/ break=on-false
  88. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+12264764502] destination_number(15198846700) =~ /^\+?1?(2264764502)$/ break=on-false
  89. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+14167577930] continue=false
  90. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+14167577930] context(public) =~ /public/ break=on-false
  91. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+14167577930] destination_number(15198846700) =~ /^\+?1?(4167577930)$/ break=on-false
  92. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+14169972944] continue=false
  93. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+14169972944] context(public) =~ /public/ break=on-false
  94. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+14169972944] destination_number(15198846700) =~ /^\+?1?(4169972944)$/ break=on-false
  95. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+15197419672] continue=false
  96. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+15197419672] context(public) =~ /public/ break=on-false
  97. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+15197419672] destination_number(15198846700) =~ /^\+?1?(5197419672)$/ break=on-false
  98. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+15197419679] continue=false
  99. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+15197419679] context(public) =~ /public/ break=on-false
  100. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+15197419679] destination_number(15198846700) =~ /^\+?1?(5197419679)$/ break=on-false
  101. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+18445712124] continue=false
  102. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+18445712124] context(public) =~ /public/ break=on-false
  103. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+18445712124] destination_number(15198846700) =~ /^\+?1?(8445712124)$/ break=on-false
  104. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+2013666069] continue=false
  105. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+2013666069] destination_number(15198846700) =~ /^\+?1?(2013666069)$/ break=on-false
  106. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+2262413076] continue=false
  107. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+2262413076] context(public) =~ /public/ break=on-false
  108. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+2262413076] destination_number(15198846700) =~ /^\+?1?(2262413076)$/ break=on-false
  109. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+2264762186] continue=false
  110. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+2264762186] context(public) =~ /public/ break=on-false
  111. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+2264762186] destination_number(15198846700) =~ /^\+?1?(2264762186)$/ break=on-false
  112. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+4162598662] continue=false
  113. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+4162598662] context(public) =~ /public/ break=on-false
  114. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+4162598662] destination_number(15198846700) =~ /^\+?1?(4162598662)$/ break=on-false
  115. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+4163688900] continue=false
  116. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+4163688900] context(public) =~ /public/ break=on-false
  117. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+4163688900] destination_number(15198846700) =~ /^\+?1?(4163688900)$/ break=on-false
  118. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+5193562296] continue=false
  119. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+5193562296] context(public) =~ /public/ break=on-false
  120. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+5193562296] destination_number(15198846700) =~ /^\+?1?(5193562296)$/ break=on-false
  121. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+5193569008] continue=false
  122. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+5193569008] context(public) =~ /public/ break=on-false
  123. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+5193569008] destination_number(15198846700) =~ /^\+?1?(5193569008)$/ break=on-false
  124. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+5193569041] continue=false
  125. 2018-08-29 13:24:48.315933 [NOTICE] mod_logfile.c:213 New log started.
  126. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+5193569041] context(public) =~ /public/ break=on-false
  127. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+5193569041] destination_number(15198846700) =~ /^\+?1?(5193569041)$/ break=on-false
  128. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+5196511551] continue=false
  129. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+5196511551] context(public) =~ /public/ break=on-false
  130. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+5196511551] destination_number(15198846700) =~ /^\+?1?(5196511551)$/ break=on-false
  131. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+5196672007] continue=false
  132. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+5196672007] context(public) =~ /public/ break=on-false
  133. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+5196672007] destination_number(15198846700) =~ /\+?1?5196672007/ break=on-false
  134. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+7053429697] continue=false
  135. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+7053429697] context(public) =~ /public/ break=on-false
  136. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+7053429697] destination_number(15198846700) =~ /^\+?1?(7053429697)$/ break=on-false
  137. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->+7059960373] continue=false
  138. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [+7059960373] context(public) =~ /public/ break=on-false
  139. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [+7059960373] destination_number(15198846700) =~ /^\+?1?(7059960373)$/ break=on-false
  140. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->12262413076] continue=false
  141. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [12262413076] context(public) =~ /public/ break=on-false
  142. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [12262413076] destination_number(15198846700) =~ /^\+?1?1(2262413076)$/ break=on-false
  143. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->12267703634] continue=false
  144. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [12267703634] context(public) =~ /public/ break=on-false
  145. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [12267703634] destination_number(15198846700) =~ /^\+?1?(2267703634)$/ break=on-false
  146. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->12267800390] continue=false
  147. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [12267800390] destination_number(15198846700) =~ /^\+?1?(2267800390)$/ break=on-false
  148. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->15199421101] continue=false
  149. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [15199421101] context(public) =~ /public/ break=on-false
  150. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [15199421101] destination_number(15198846700) =~ /^\+?1?(5199421101)$/ break=on-false
  151. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->16476991518] continue=false
  152. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [16476991518] destination_number(15198846700) =~ /^\+?1?(6476991518)$/ break=on-false
  153. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->17059961737] continue=false
  154. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [17059961737] destination_number(15198846700) =~ /^\+?1?(17059961737)$/ break=on-false
  155. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->18002658900] continue=false
  156. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [18002658900] context(public) =~ /public/ break=on-false
  157. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [18002658900] destination_number(15198846700) =~ /^\+?1?(8002658900)$/ break=on-false
  158. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->18184899728] continue=false
  159. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [18184899728] destination_number(15198846700) =~ /^\+?1?(8184899728)$/ break=on-false
  160. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->2264761354] continue=false
  161. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [2264761354] context(public) =~ /public/ break=on-false
  162. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [2264761354] destination_number(15198846700) =~ /^\+?1?(2264761354)$/ break=on-false
  163. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->5193562294] continue=false
  164. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [5193562294] context(public) =~ /public/ break=on-false
  165. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [5193562294] destination_number(15198846700) =~ /^\+?1?(5193562294)$/ break=on-false
  166. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->5198846097] continue=false
  167. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [5198846097] context(public) =~ /public/ break=on-false
  168. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [5198846097] destination_number(15198846700) =~ /^\+?1?(5198846097)$/ break=on-false
  169. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [public->5198846700] continue=false
  170. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [5198846700] context(public) =~ /public/ break=on-false
  171. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [5198846700] destination_number(15198846700) =~ /^\+?1?(5198846700)$/ break=on-false
  172. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(call_direction=inbound) INLINE
  173. EXECUTE sofia/external/5195752944@208.89.129.46 set(call_direction=inbound)
  174. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [call_direction]=[inbound]
  175. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(domain_uuid=64b5d36d-f8e3-459d-80fd-6f1069aaee4c) INLINE
  176. EXECUTE sofia/external/5195752944@208.89.129.46 set(domain_uuid=64b5d36d-f8e3-459d-80fd-6f1069aaee4c)
  177. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [domain_uuid]=[64b5d36d-f8e3-459d-80fd-6f1069aaee4c]
  178. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(domain_name=thecarmel.uphone.ca) INLINE
  179. EXECUTE sofia/external/5195752944@208.89.129.46 set(domain_name=thecarmel.uphone.ca)
  180. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [domain_name]=[thecarmel.uphone.ca]
  181. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(accountcode=thecarmel.uphone.ca)
  182. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(call_direction=inbound)
  183. Dialplan: sofia/external/5195752944@208.89.129.46 Action transfer(7000 XML thecarmel.uphone.ca)
  184. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:286 (sofia/external/5195752944@208.89.129.46) State Change CS_ROUTING -> CS_EXECUTE
  185. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING going to sleep
  186. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_EXECUTE (Cur 1 Tot 46967)
  187. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE
  188. 2018-08-29 13:24:48.315933 [DEBUG] mod_sofia.c:198 sofia/external/5195752944@208.89.129.46 SOFIA EXECUTE
  189. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:328 sofia/external/5195752944@208.89.129.46 Standard EXECUTE
  190. EXECUTE sofia/external/5195752944@208.89.129.46 set(call_direction=inbound)
  191. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [call_direction]=[inbound]
  192. EXECUTE sofia/external/5195752944@208.89.129.46 export(caller_id_name=Adrien Alexson)
  193. 2018-08-29 13:24:48.315933 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [caller_id_name]=[Adrien Alexson]
  194. EXECUTE sofia/external/5195752944@208.89.129.46 log(test099-Adrien Alexson)
  195. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1724 Alexson
  196. EXECUTE sofia/external/5195752944@208.89.129.46 set(accountcode=thecarmel.uphone.ca)
  197. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [accountcode]=[thecarmel.uphone.ca]
  198. EXECUTE sofia/external/5195752944@208.89.129.46 set(call_direction=inbound)
  199. 2018-08-29 13:24:48.315933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [call_direction]=[inbound]
  200. EXECUTE sofia/external/5195752944@208.89.129.46 transfer(7000 XML thecarmel.uphone.ca)
  201. 2018-08-29 13:24:48.315933 [DEBUG] switch_ivr.c:2165 (sofia/external/5195752944@208.89.129.46) State Change CS_EXECUTE -> CS_ROUTING
  202. 2018-08-29 13:24:48.315933 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/5195752944@208.89.129.46 to XML[7000@thecarmel.uphone.ca]
  203. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE going to sleep
  204. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_ROUTING (Cur 1 Tot 46967)
  205. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING
  206. 2018-08-29 13:24:48.315933 [DEBUG] mod_sofia.c:143 sofia/external/5195752944@208.89.129.46 SOFIA ROUTING
  207. 2018-08-29 13:24:48.315933 [DEBUG] switch_core_state_machine.c:236 sofia/external/5195752944@208.89.129.46 Standard ROUTING
  208. 2018-08-29 13:24:48.315933 [INFO] mod_dialplan_xml.c:637 Processing 5195752944 <5195752944>->7000 in context thecarmel.uphone.ca
  209. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->user_exists] continue=true
  210. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_exists] () =~ // break=on-false
  211. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  212. 2018-08-29 13:24:48.335925 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  213. 2018-08-29 13:24:48.335925 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  214. EXECUTE sofia/external/5195752944@208.89.129.46 set(user_exists=false)
  215. 2018-08-29 13:24:48.335925 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [user_exists]=[false]
  216. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  217. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call-direction] continue=true
  218. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
  219. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->variables] continue=true
  220. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [variables] () =~ // break=on-false
  221. Dialplan: sofia/external/5195752944@208.89.129.46 Action export(origination_callee_id_name=${destination_number})
  222. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  223. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_block] continue=true
  224. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_block] ${call_blocked}() =~ /^true$/ break=on-false
  225. Dialplan: sofia/external/5195752944@208.89.129.46 ANTI-Action lua(app.lua call_block)
  226. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->user_record] continue=true
  227. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] () =~ // break=on-false
  228. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  229. 2018-08-29 13:24:48.335925 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  230. 2018-08-29 13:24:48.335925 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  231. 2018-08-29 13:24:48.335925 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  232. 2018-08-29 13:24:48.355928 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  233. EXECUTE sofia/external/5195752944@208.89.129.46 set(user_record=)
  234. 2018-08-29 13:24:48.355928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [user_record]=[UNDEF]
  235. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  236. 2018-08-29 13:24:48.355928 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  237. 2018-08-29 13:24:48.355928 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  238. EXECUTE sofia/external/5195752944@208.89.129.46 set(from_user_exists=false)
  239. 2018-08-29 13:24:48.355928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [from_user_exists]=[false]
  240. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  241. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  242. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  243. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  244. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  245. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  246. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  247. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  248. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  249. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  250. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  251. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  252. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  253. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  254. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  255. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  256. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  257. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  258. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  259. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  260. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  261. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  262. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  263. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  264. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->redial] continue=true
  265. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [redial] destination_number(7000) =~ /^(redial|\*870)$/ break=on-true
  266. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [redial] () =~ // break=never
  267. Dialplan: sofia/external/5195752944@208.89.129.46 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  268. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->speed_dial] continue=false
  269. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [speed_dial] destination_number(7000) =~ /^\*0(.*)$/ break=on-false
  270. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->911-Iris] continue=false
  271. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [911-Iris] destination_number(7000) =~ /^911$/ break=on-false
  272. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->911-Iris-copy] continue=false
  273. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [911-Iris-copy] destination_number(7000) =~ /^911$/ break=on-false
  274. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->922-test] continue=false
  275. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [922-test] destination_number(7000) =~ /^922$/ break=on-false
  276. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->country_variables] continue=true
  277. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [country_variables] () =~ // break=on-false
  278. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(toll_allow=all) INLINE
  279. EXECUTE sofia/external/5195752944@208.89.129.46 set(toll_allow=all)
  280. 2018-08-29 13:24:48.355928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [toll_allow]=[all]
  281. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->outbound_call_restriction] continue=true
  282. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [outbound_call_restriction] ${call_direction}(inbound) =~ /^(outbound)$/ break=on-false
  283. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->local_dids] continue=false
  284. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [local_dids] destination_number(7000) =~ /^\+?1?(\d{10})$/ break=on-false
  285. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->CanadaCalling] continue=false
  286. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [CanadaCalling] ${toll_allow}(all) =~ /all|canada|northamerica/ break=on-false
  287. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [CanadaCalling] destination_number(7000) =~ /^\+?1?((204|226|236|249|250|289|306|343|343|365|403|416|418|431|437|438|450|506|514|519|548|579|581|587|604|613|613|639|647|705|709|778|780|782|782|807|819|825|867|867|867|873|902|902|905)[2-9]\d{6})$/ break=on-false
  288. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->US_Calling] continue=false
  289. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [US_Calling] ${toll_allow}(all) =~ /all|northamerica|unitedstates/ break=on-false
  290. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [US_Calling] destination_number(7000) =~ /^\+?1?((201|202|203|205|206|207|208|209|210|212|213|214|215|216|217|218|219|220|224|225|227|228|229|231|234|239|240|248|251|252|253|254|256|260|262|267|269|270|272|274|276|281|283|301|302|303|304|305|307|308|309|310|312|313|314|315|316|317|318|319|320|321|323|325|327|330|331|332|334|336|337|339|346|347|351|352|360|361|364|380|385|386|401|402|404|405|406|407|408|409|410|412|413|414|415|417|419|423|424|425|430|432|434|435|440|442|443|447|458|463|464|469|470|475|478|479|480|484|501|502|503|504|505|507|508|509|510|512|513|515|516|517|518|520|530|531|534|539|540|541|551|557|559|561|562|563|564|567|570|571|573|574|575|580|585|586|601|602|603|605|606|607|608|609|610|612|614|615|616|617|618|619|620|623|626|628|629|630|631|636|641|646|650|651|657|659|660|661|662|667|669|678|679|680|681|682|689|701|702|703|704|706|707|708|712|713|714|715|716|717|718|719|720|724|725|726|727|730|731|732|734|737|740|743|747|754|757|760|762|763|765|769|770|772|773|774|775|779|781|785|786|801|802|803|804|805|806|808|810|812|813|814|815|816|817|818|828|830|831|832|838|843|845|847|848|850|854|856|857|858|859|860|862|863|864|865|870|872|878|901|903|904|906|907|908|909|910|912|913|914|915|916|917|918|919|920|925|928|929|930|931|934|936|937|938|940|941|947|949|951|952|954|956|959|970|971|972|973|975|978|979|980|984|985|986|989)[2-9]\d{6})$/ break=on-false
  291. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Toll-Free_Calling] continue=false
  292. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Toll-Free_Calling] destination_number(7000) =~ /^\+?1?(8(00|33|44|55|66|77|88)[2-9]\d{6})$/ break=on-false
  293. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->310-Iris] continue=false
  294. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [310-Iris] destination_number(7000) =~ /^310(\d{4})$/ break=on-false
  295. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Anguilla] continue=false
  296. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Anguilla] destination_number(7000) =~ /^\+?1?(264[2-9]\d{6})$/ break=on-false
  297. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Bermuda] continue=false
  298. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [Bermuda] ${toll_allow}(all) =~ /all|northamerica|bermuda/ break=on-false
  299. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Bermuda] destination_number(7000) =~ /^\+?1?(441[2-9]\d{6})$/ break=on-false
  300. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Jamaica] continue=false
  301. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Jamaica] destination_number(7000) =~ /^\+?1?(876[2-9]\d{6})$/ break=on-false
  302. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->agent_status] continue=false
  303. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [agent_status] destination_number(7000) =~ /^\*22$/ break=on-false
  304. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->International-LCR] continue=false
  305. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [International-LCR] ${toll_allow}(all) =~ /all|international|CAUSInternational/ break=on-false
  306. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [International-LCR] destination_number(7000) =~ /^(011|00)(\d{9,17})$/ break=on-false
  307. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->sbc.voxbeam.com.International-copy] continue=false
  308. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [sbc.voxbeam.com.International-copy] destination_number(7000) =~ /^(011|00)(\d{9,17})$/ break=on-false
  309. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->agent_status_id] continue=false
  310. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [agent_status_id] destination_number(7000) =~ /^\*23$/ break=on-false
  311. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->group-intercept] continue=false
  312. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [group-intercept] destination_number(7000) =~ /^\*8$/ break=on-false
  313. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->page-extension] continue=false
  314. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [page-extension] destination_number(7000) =~ /^\*8(\d{2,7})$/ break=on-false
  315. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->eavesdrop] continue=false
  316. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [eavesdrop] destination_number(7000) =~ /^\*33(\d{2,7})$/ break=on-false
  317. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_privacy] continue=false
  318. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_privacy] destination_number(7000) =~ /^\*67(\d+)$/ break=on-false
  319. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_return] continue=false
  320. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_return] destination_number(7000) =~ /^\*69$/ break=on-false
  321. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->extension_queue] continue=false
  322. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [extension_queue] destination_number(7000) =~ /^\*800(.*)$/ break=on-false
  323. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->intercept-ext] continue=false
  324. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [intercept-ext] destination_number(7000) =~ /^\*\*(\d+)$/ break=on-false
  325. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->intercept-ext-polycom] continue=false
  326. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [intercept-ext-polycom] destination_number(7000) =~ /^\*97(\d+)$/ break=on-false
  327. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->dx] continue=false
  328. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [dx] destination_number(7000) =~ /^dx$/ break=on-false
  329. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->no-ring] continue=true
  330. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [no-ring] destination_number(7000) =~ /^4000$/ break=on-false
  331. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->att_xfer] continue=false
  332. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [att_xfer] destination_number(7000) =~ /^att_xfer$/ break=on-false
  333. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Carmel-Fax] continue=false
  334. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Carmel-Fax] destination_number(7000) =~ /^125$/ break=on-false
  335. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->extension-to-voicemail] continue=false
  336. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
  337. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->send_to_voicemail] continue=false
  338. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [send_to_voicemail] destination_number(7000) =~ /^\*99(\d{2,10})$/ break=on-false
  339. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->vmain] continue=false
  340. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [vmain] destination_number(7000) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
  341. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->xfer_vm] continue=false
  342. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [xfer_vm] destination_number(7000) =~ /^xfer_vm$/ break=on-false
  343. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->is_transfer] continue=false
  344. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [is_transfer] destination_number(7000) =~ /^is_transfer$/ break=on-false
  345. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->vmain_user] continue=false
  346. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [vmain_user] destination_number(7000) =~ /^\*97$/ break=on-false
  347. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->allphones] continue=
  348. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [allphones] destination_number(7000) =~ /^4200$/ break=on-false
  349. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->private] continue=
  350. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [private] destination_number(7000) =~ /^\*7000$/ break=on-true
  351. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [private] destination_number(7000) =~ /^7000$/ break=on-false
  352. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(call_flow_uuid=0d092c7d-7540-4425-bc1c-da2489e2de39)
  353. Dialplan: sofia/external/5195752944@208.89.129.46 Action lua(call_flow.lua)
  354. 2018-08-29 13:24:48.355928 [DEBUG] switch_core_state_machine.c:286 (sofia/external/5195752944@208.89.129.46) State Change CS_ROUTING -> CS_EXECUTE
  355. 2018-08-29 13:24:48.355928 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING going to sleep
  356. 2018-08-29 13:24:48.355928 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_EXECUTE (Cur 1 Tot 46967)
  357. 2018-08-29 13:24:48.355928 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE
  358. 2018-08-29 13:24:48.355928 [DEBUG] mod_sofia.c:198 sofia/external/5195752944@208.89.129.46 SOFIA EXECUTE
  359. 2018-08-29 13:24:48.355928 [DEBUG] switch_core_state_machine.c:328 sofia/external/5195752944@208.89.129.46 Standard EXECUTE
  360. EXECUTE sofia/external/5195752944@208.89.129.46 export(origination_callee_id_name=7000)
  361. 2018-08-29 13:24:48.355928 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[7000]
  362. EXECUTE sofia/external/5195752944@208.89.129.46 set(RFC2822_DATE=Wed, 29 Aug 2018 13:24:48 -0400)
  363. 2018-08-29 13:24:48.355928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [RFC2822_DATE]=[Wed, 29 Aug 2018 13:24:48 -0400]
  364. EXECUTE sofia/external/5195752944@208.89.129.46 lua(app.lua call_block)
  365. 2018-08-29 13:24:48.355928 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('call_block', '')
  366. 2018-08-29 13:24:48.355928 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  367. 2018-08-29 13:24:48.355928 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  368. 2018-08-29 13:24:48.355928 [DEBUG] switch_cpp.cpp:1112 sofia/external/5195752944@208.89.129.46 destroy/unlink session from object
  369. EXECUTE sofia/external/5195752944@208.89.129.46 hash(insert/thecarmel.uphone.ca-last_dial/5195752944/7000)
  370. EXECUTE sofia/external/5195752944@208.89.129.46 set(call_flow_uuid=0d092c7d-7540-4425-bc1c-da2489e2de39)
  371. 2018-08-29 13:24:48.355928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [call_flow_uuid]=[0d092c7d-7540-4425-bc1c-da2489e2de39]
  372. EXECUTE sofia/external/5195752944@208.89.129.46 lua(call_flow.lua)
  373. 2018-08-29 13:24:48.355928 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  374. 2018-08-29 13:24:48.375932 [NOTICE] switch_cpp.cpp:1365 [call_flow] execute transfer 4000 XML thecarmel.uphone.ca
  375. EXECUTE sofia/external/5195752944@208.89.129.46 transfer(4000 XML thecarmel.uphone.ca)
  376. 2018-08-29 13:24:48.375932 [DEBUG] switch_ivr.c:2165 (sofia/external/5195752944@208.89.129.46) State Change CS_EXECUTE -> CS_ROUTING
  377. 2018-08-29 13:24:48.375932 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/5195752944@208.89.129.46 to XML[4000@thecarmel.uphone.ca]
  378. 2018-08-29 13:24:48.375932 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  379. 2018-08-29 13:24:48.375932 [DEBUG] switch_cpp.cpp:1112 sofia/external/5195752944@208.89.129.46 destroy/unlink session from object
  380. 2018-08-29 13:24:48.375932 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE going to sleep
  381. 2018-08-29 13:24:48.375932 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_ROUTING (Cur 1 Tot 46967)
  382. 2018-08-29 13:24:48.375932 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING
  383. 2018-08-29 13:24:48.375932 [DEBUG] mod_sofia.c:143 sofia/external/5195752944@208.89.129.46 SOFIA ROUTING
  384. 2018-08-29 13:24:48.375932 [DEBUG] switch_core_state_machine.c:236 sofia/external/5195752944@208.89.129.46 Standard ROUTING
  385. 2018-08-29 13:24:48.375932 [INFO] mod_dialplan_xml.c:637 Processing 5195752944 <5195752944>->4000 in context thecarmel.uphone.ca
  386. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->user_exists] continue=true
  387. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_exists] () =~ // break=on-false
  388. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  389. 2018-08-29 13:24:48.375932 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  390. 2018-08-29 13:24:48.375932 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  391. EXECUTE sofia/external/5195752944@208.89.129.46 set(user_exists=false)
  392. 2018-08-29 13:24:48.375932 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [user_exists]=[false]
  393. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  394. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call-direction] continue=true
  395. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
  396. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->variables] continue=true
  397. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [variables] () =~ // break=on-false
  398. Dialplan: sofia/external/5195752944@208.89.129.46 Action export(origination_callee_id_name=${destination_number})
  399. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  400. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_block] continue=true
  401. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_block] ${call_blocked}() =~ /^true$/ break=on-false
  402. Dialplan: sofia/external/5195752944@208.89.129.46 ANTI-Action lua(app.lua call_block)
  403. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->user_record] continue=true
  404. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] () =~ // break=on-false
  405. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  406. 2018-08-29 13:24:48.375932 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  407. 2018-08-29 13:24:48.395924 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  408. 2018-08-29 13:24:48.395924 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  409. 2018-08-29 13:24:48.395924 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  410. EXECUTE sofia/external/5195752944@208.89.129.46 set(user_record=)
  411. 2018-08-29 13:24:48.395924 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [user_record]=[UNDEF]
  412. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  413. 2018-08-29 13:24:48.395924 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  414. 2018-08-29 13:24:48.395924 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  415. EXECUTE sofia/external/5195752944@208.89.129.46 set(from_user_exists=false)
  416. 2018-08-29 13:24:48.395924 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [from_user_exists]=[false]
  417. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  418. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  419. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  420. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  421. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  422. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  423. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  424. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  425. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  426. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  427. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  428. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  429. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  430. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  431. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  432. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  433. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  434. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  435. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  436. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  437. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  438. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  439. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  440. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  441. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->redial] continue=true
  442. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [redial] destination_number(4000) =~ /^(redial|\*870)$/ break=on-true
  443. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [redial] () =~ // break=never
  444. Dialplan: sofia/external/5195752944@208.89.129.46 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  445. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->speed_dial] continue=false
  446. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [speed_dial] destination_number(4000) =~ /^\*0(.*)$/ break=on-false
  447. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->911-Iris] continue=false
  448. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [911-Iris] destination_number(4000) =~ /^911$/ break=on-false
  449. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->911-Iris-copy] continue=false
  450. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [911-Iris-copy] destination_number(4000) =~ /^911$/ break=on-false
  451. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->922-test] continue=false
  452. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [922-test] destination_number(4000) =~ /^922$/ break=on-false
  453. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->country_variables] continue=true
  454. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [country_variables] () =~ // break=on-false
  455. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(toll_allow=all) INLINE
  456. EXECUTE sofia/external/5195752944@208.89.129.46 set(toll_allow=all)
  457. 2018-08-29 13:24:48.395924 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [toll_allow]=[all]
  458. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->outbound_call_restriction] continue=true
  459. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [outbound_call_restriction] ${call_direction}(inbound) =~ /^(outbound)$/ break=on-false
  460. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->local_dids] continue=false
  461. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [local_dids] destination_number(4000) =~ /^\+?1?(\d{10})$/ break=on-false
  462. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->CanadaCalling] continue=false
  463. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [CanadaCalling] ${toll_allow}(all) =~ /all|canada|northamerica/ break=on-false
  464. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [CanadaCalling] destination_number(4000) =~ /^\+?1?((204|226|236|249|250|289|306|343|343|365|403|416|418|431|437|438|450|506|514|519|548|579|581|587|604|613|613|639|647|705|709|778|780|782|782|807|819|825|867|867|867|873|902|902|905)[2-9]\d{6})$/ break=on-false
  465. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->US_Calling] continue=false
  466. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [US_Calling] ${toll_allow}(all) =~ /all|northamerica|unitedstates/ break=on-false
  467. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [US_Calling] destination_number(4000) =~ /^\+?1?((201|202|203|205|206|207|208|209|210|212|213|214|215|216|217|218|219|220|224|225|227|228|229|231|234|239|240|248|251|252|253|254|256|260|262|267|269|270|272|274|276|281|283|301|302|303|304|305|307|308|309|310|312|313|314|315|316|317|318|319|320|321|323|325|327|330|331|332|334|336|337|339|346|347|351|352|360|361|364|380|385|386|401|402|404|405|406|407|408|409|410|412|413|414|415|417|419|423|424|425|430|432|434|435|440|442|443|447|458|463|464|469|470|475|478|479|480|484|501|502|503|504|505|507|508|509|510|512|513|515|516|517|518|520|530|531|534|539|540|541|551|557|559|561|562|563|564|567|570|571|573|574|575|580|585|586|601|602|603|605|606|607|608|609|610|612|614|615|616|617|618|619|620|623|626|628|629|630|631|636|641|646|650|651|657|659|660|661|662|667|669|678|679|680|681|682|689|701|702|703|704|706|707|708|712|713|714|715|716|717|718|719|720|724|725|726|727|730|731|732|734|737|740|743|747|754|757|760|762|763|765|769|770|772|773|774|775|779|781|785|786|801|802|803|804|805|806|808|810|812|813|814|815|816|817|818|828|830|831|832|838|843|845|847|848|850|854|856|857|858|859|860|862|863|864|865|870|872|878|901|903|904|906|907|908|909|910|912|913|914|915|916|917|918|919|920|925|928|929|930|931|934|936|937|938|940|941|947|949|951|952|954|956|959|970|971|972|973|975|978|979|980|984|985|986|989)[2-9]\d{6})$/ break=on-false
  468. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Toll-Free_Calling] continue=false
  469. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Toll-Free_Calling] destination_number(4000) =~ /^\+?1?(8(00|33|44|55|66|77|88)[2-9]\d{6})$/ break=on-false
  470. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->310-Iris] continue=false
  471. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [310-Iris] destination_number(4000) =~ /^310(\d{4})$/ break=on-false
  472. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Anguilla] continue=false
  473. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Anguilla] destination_number(4000) =~ /^\+?1?(264[2-9]\d{6})$/ break=on-false
  474. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Bermuda] continue=false
  475. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [Bermuda] ${toll_allow}(all) =~ /all|northamerica|bermuda/ break=on-false
  476. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Bermuda] destination_number(4000) =~ /^\+?1?(441[2-9]\d{6})$/ break=on-false
  477. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Jamaica] continue=false
  478. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Jamaica] destination_number(4000) =~ /^\+?1?(876[2-9]\d{6})$/ break=on-false
  479. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->agent_status] continue=false
  480. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [agent_status] destination_number(4000) =~ /^\*22$/ break=on-false
  481. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->International-LCR] continue=false
  482. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [International-LCR] ${toll_allow}(all) =~ /all|international|CAUSInternational/ break=on-false
  483. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [International-LCR] destination_number(4000) =~ /^(011|00)(\d{9,17})$/ break=on-false
  484. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->sbc.voxbeam.com.International-copy] continue=false
  485. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [sbc.voxbeam.com.International-copy] destination_number(4000) =~ /^(011|00)(\d{9,17})$/ break=on-false
  486. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->agent_status_id] continue=false
  487. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [agent_status_id] destination_number(4000) =~ /^\*23$/ break=on-false
  488. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->group-intercept] continue=false
  489. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [group-intercept] destination_number(4000) =~ /^\*8$/ break=on-false
  490. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->page-extension] continue=false
  491. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [page-extension] destination_number(4000) =~ /^\*8(\d{2,7})$/ break=on-false
  492. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->eavesdrop] continue=false
  493. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [eavesdrop] destination_number(4000) =~ /^\*33(\d{2,7})$/ break=on-false
  494. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_privacy] continue=false
  495. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_privacy] destination_number(4000) =~ /^\*67(\d+)$/ break=on-false
  496. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_return] continue=false
  497. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_return] destination_number(4000) =~ /^\*69$/ break=on-false
  498. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->extension_queue] continue=false
  499. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [extension_queue] destination_number(4000) =~ /^\*800(.*)$/ break=on-false
  500. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->intercept-ext] continue=false
  501. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [intercept-ext] destination_number(4000) =~ /^\*\*(\d+)$/ break=on-false
  502. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->intercept-ext-polycom] continue=false
  503. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [intercept-ext-polycom] destination_number(4000) =~ /^\*97(\d+)$/ break=on-false
  504. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->dx] continue=false
  505. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [dx] destination_number(4000) =~ /^dx$/ break=on-false
  506. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->no-ring] continue=true
  507. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [no-ring] destination_number(4000) =~ /^4000$/ break=on-false
  508. Dialplan: sofia/external/5195752944@208.89.129.46 Date/TimeMatch (FAIL) [no-ring] break=never
  509. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [no-ring] destination_number(4000) =~ /^4000$/ break=on-false
  510. Dialplan: sofia/external/5195752944@208.89.129.46 Date/TimeMatch (FAIL) [no-ring] break=never
  511. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [no-ring] destination_number(4000) =~ /^4000$/ break=on-false
  512. Dialplan: sofia/external/5195752944@208.89.129.46 Date/TimeMatch (FAIL) [no-ring] break=never
  513. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [no-ring] destination_number(4000) =~ /^4000$/ break=on-false
  514. Dialplan: sofia/external/5195752944@208.89.129.46 Date/TimeMatch (FAIL) [no-ring] break=never
  515. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [no-ring] destination_number(4000) =~ /^4000$/ break=on-false
  516. Dialplan: sofia/external/5195752944@208.89.129.46 Date/TimeMatch (FAIL) [no-ring] break=never
  517. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [no-ring] destination_number(4000) =~ /^4000$/ break=on-false
  518. Dialplan: sofia/external/5195752944@208.89.129.46 Action transfer(4200 XML thecarmel.uphone.ca)
  519. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->att_xfer] continue=false
  520. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [att_xfer] destination_number(4000) =~ /^att_xfer$/ break=on-false
  521. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Carmel-Fax] continue=false
  522. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Carmel-Fax] destination_number(4000) =~ /^125$/ break=on-false
  523. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->extension-to-voicemail] continue=false
  524. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
  525. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->send_to_voicemail] continue=false
  526. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [send_to_voicemail] destination_number(4000) =~ /^\*99(\d{2,10})$/ break=on-false
  527. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->vmain] continue=false
  528. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [vmain] destination_number(4000) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
  529. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->xfer_vm] continue=false
  530. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [xfer_vm] destination_number(4000) =~ /^xfer_vm$/ break=on-false
  531. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->is_transfer] continue=false
  532. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [is_transfer] destination_number(4000) =~ /^is_transfer$/ break=on-false
  533. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->vmain_user] continue=false
  534. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [vmain_user] destination_number(4000) =~ /^\*97$/ break=on-false
  535. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->allphones] continue=
  536. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [allphones] destination_number(4000) =~ /^4200$/ break=on-false
  537. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->private] continue=
  538. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [private] destination_number(4000) =~ /^\*7000$/ break=on-true
  539. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [private] destination_number(4000) =~ /^7000$/ break=on-false
  540. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->cf] continue=false
  541. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [cf] destination_number(4000) =~ /^cf$/ break=on-false
  542. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->delay_echo] continue=false
  543. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [delay_echo] destination_number(4000) =~ /^\*9195$/ break=on-false
  544. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->echo] continue=false
  545. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [echo] destination_number(4000) =~ /^\*9196$/ break=on-false
  546. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->is_zrtp_secure] continue=true
  547. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
  548. Dialplan: sofia/external/5195752944@208.89.129.46 ANTI-Action eval(not_secure)
  549. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->milliwatt] continue=false
  550. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [milliwatt] destination_number(4000) =~ /^\*9197$/ break=on-false
  551. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->is_secure] continue=true
  552. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
  553. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->tone_stream] continue=false
  554. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [tone_stream] destination_number(4000) =~ /^\*9198$/ break=on-false
  555. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->hold_music] continue=false
  556. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [hold_music] destination_number(4000) =~ /^\*9664$/ break=on-false
  557. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->recordings] continue=false
  558. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [recordings] destination_number(4000) =~ /^\*(732)$/ break=on-false
  559. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->directory] continue=false
  560. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [directory] destination_number(4000) =~ /^\*411$/ break=on-false
  561. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->wake-up] continue=false
  562. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [wake-up] destination_number(4000) =~ /^\*(925)$/ break=on-false
  563. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Custom_valet_park_in] continue=false
  564. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Custom_valet_park_in] destination_number(4000) =~ /^(park\+)?(\*5900)$/ break=on-false
  565. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Custom_valet_park_out] continue=false
  566. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Custom_valet_park_out] destination_number(4000) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=on-false
  567. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->operator] continue=false
  568. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [operator] destination_number(4000) =~ /^0$|^operator$/ break=on-false
  569. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->operator-forward] continue=false
  570. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [operator-forward] destination_number(4000) =~ /^\*000$/ break=on-false
  571. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->do-not-disturb] continue=false
  572. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [do-not-disturb] destination_number(4000) =~ /^\*77$/ break=on-true
  573. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [do-not-disturb] destination_number(4000) =~ /^\*78$|\*363$/ break=on-true
  574. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [do-not-disturb] destination_number(4000) =~ /^\*79$/ break=on-false
  575. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call-forward] continue=false
  576. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call-forward] destination_number(4000) =~ /^\*72$/ break=on-true
  577. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call-forward] destination_number(4000) =~ /^\*73$/ break=on-true
  578. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call-forward] destination_number(4000) =~ /^\*74$/ break=on-true
  579. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call forward all] continue=
  580. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call forward all] ${user_exists}(false) =~ /^true/ break=on-false
  581. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->follow-me] continue=false
  582. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [follow-me] destination_number(4000) =~ /^\*21$/ break=on-false
  583. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->clear_sip_auto_answer] continue=true
  584. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
  585. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->talking clock date and time] continue=false
  586. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [talking clock date and time] destination_number(4000) =~ /^\*9172$/ break=on-false
  587. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->talking clock time] continue=false
  588. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [talking clock time] destination_number(4000) =~ /^\*9170$/ break=on-false
  589. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->talking clock date] continue=false
  590. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [talking clock date] destination_number(4000) =~ /^\*9171$/ break=on-false
  591. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_screen] continue=true
  592. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_screen] ${call_screen_enabled}() =~ /^true$/ break=on-false
  593. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->local_extension] continue=true
  594. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [local_extension] ${user_exists}(false) =~ /true/ break=on-false
  595. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->voicemail] continue=false
  596. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [voicemail] ${user_exists}(false) =~ /true/ break=on-false
  597. 2018-08-29 13:24:48.395924 [DEBUG] switch_core_state_machine.c:286 (sofia/external/5195752944@208.89.129.46) State Change CS_ROUTING -> CS_EXECUTE
  598. 2018-08-29 13:24:48.395924 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING going to sleep
  599. 2018-08-29 13:24:48.395924 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_EXECUTE (Cur 1 Tot 46967)
  600. 2018-08-29 13:24:48.395924 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE
  601. 2018-08-29 13:24:48.395924 [DEBUG] mod_sofia.c:198 sofia/external/5195752944@208.89.129.46 SOFIA EXECUTE
  602. 2018-08-29 13:24:48.395924 [DEBUG] switch_core_state_machine.c:328 sofia/external/5195752944@208.89.129.46 Standard EXECUTE
  603. EXECUTE sofia/external/5195752944@208.89.129.46 export(origination_callee_id_name=4000)
  604. 2018-08-29 13:24:48.395924 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[4000]
  605. EXECUTE sofia/external/5195752944@208.89.129.46 set(RFC2822_DATE=Wed, 29 Aug 2018 13:24:48 -0400)
  606. 2018-08-29 13:24:48.395924 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [RFC2822_DATE]=[Wed, 29 Aug 2018 13:24:48 -0400]
  607. EXECUTE sofia/external/5195752944@208.89.129.46 lua(app.lua call_block)
  608. 2018-08-29 13:24:48.395924 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('call_block', '')
  609. 2018-08-29 13:24:48.395924 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  610. 2018-08-29 13:24:48.415926 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  611. 2018-08-29 13:24:48.415926 [DEBUG] switch_cpp.cpp:1112 sofia/external/5195752944@208.89.129.46 destroy/unlink session from object
  612. EXECUTE sofia/external/5195752944@208.89.129.46 hash(insert/thecarmel.uphone.ca-last_dial/5195752944/4000)
  613. EXECUTE sofia/external/5195752944@208.89.129.46 transfer(4200 XML thecarmel.uphone.ca)
  614. 2018-08-29 13:24:48.415926 [DEBUG] switch_ivr.c:2165 (sofia/external/5195752944@208.89.129.46) State Change CS_EXECUTE -> CS_ROUTING
  615. 2018-08-29 13:24:48.415926 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/5195752944@208.89.129.46 to XML[4200@thecarmel.uphone.ca]
  616. 2018-08-29 13:24:48.415926 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE going to sleep
  617. 2018-08-29 13:24:48.415926 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_ROUTING (Cur 1 Tot 46967)
  618. 2018-08-29 13:24:48.415926 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING
  619. 2018-08-29 13:24:48.415926 [DEBUG] mod_sofia.c:143 sofia/external/5195752944@208.89.129.46 SOFIA ROUTING
  620. 2018-08-29 13:24:48.415926 [DEBUG] switch_core_state_machine.c:236 sofia/external/5195752944@208.89.129.46 Standard ROUTING
  621. 2018-08-29 13:24:48.415926 [INFO] mod_dialplan_xml.c:637 Processing 5195752944 <5195752944>->4200 in context thecarmel.uphone.ca
  622. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->user_exists] continue=true
  623. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_exists] () =~ // break=on-false
  624. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  625. 2018-08-29 13:24:48.415926 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  626. 2018-08-29 13:24:48.415926 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  627. EXECUTE sofia/external/5195752944@208.89.129.46 set(user_exists=false)
  628. 2018-08-29 13:24:48.415926 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [user_exists]=[false]
  629. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  630. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call-direction] continue=true
  631. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
  632. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->variables] continue=true
  633. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [variables] () =~ // break=on-false
  634. Dialplan: sofia/external/5195752944@208.89.129.46 Action export(origination_callee_id_name=${destination_number})
  635. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  636. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_block] continue=true
  637. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_block] ${call_blocked}() =~ /^true$/ break=on-false
  638. Dialplan: sofia/external/5195752944@208.89.129.46 ANTI-Action lua(app.lua call_block)
  639. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->user_record] continue=true
  640. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] () =~ // break=on-false
  641. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  642. 2018-08-29 13:24:48.435922 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  643. 2018-08-29 13:24:48.435922 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  644. 2018-08-29 13:24:48.435922 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  645. 2018-08-29 13:24:48.435922 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  646. EXECUTE sofia/external/5195752944@208.89.129.46 set(user_record=)
  647. 2018-08-29 13:24:48.435922 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [user_record]=[UNDEF]
  648. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  649. 2018-08-29 13:24:48.435922 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  650. 2018-08-29 13:24:48.435922 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  651. EXECUTE sofia/external/5195752944@208.89.129.46 set(from_user_exists=false)
  652. 2018-08-29 13:24:48.435922 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [from_user_exists]=[false]
  653. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  654. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  655. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  656. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  657. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  658. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  659. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  660. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  661. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  662. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  663. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  664. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  665. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  666. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  667. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  668. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  669. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  670. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  671. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  672. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  673. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  674. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  675. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  676. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  677. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->redial] continue=true
  678. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [redial] destination_number(4200) =~ /^(redial|\*870)$/ break=on-true
  679. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [redial] () =~ // break=never
  680. Dialplan: sofia/external/5195752944@208.89.129.46 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  681. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->speed_dial] continue=false
  682. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [speed_dial] destination_number(4200) =~ /^\*0(.*)$/ break=on-false
  683. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->911-Iris] continue=false
  684. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [911-Iris] destination_number(4200) =~ /^911$/ break=on-false
  685. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->911-Iris-copy] continue=false
  686. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [911-Iris-copy] destination_number(4200) =~ /^911$/ break=on-false
  687. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->922-test] continue=false
  688. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [922-test] destination_number(4200) =~ /^922$/ break=on-false
  689. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->country_variables] continue=true
  690. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [country_variables] () =~ // break=on-false
  691. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(toll_allow=all) INLINE
  692. EXECUTE sofia/external/5195752944@208.89.129.46 set(toll_allow=all)
  693. 2018-08-29 13:24:48.455922 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [toll_allow]=[all]
  694. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->outbound_call_restriction] continue=true
  695. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [outbound_call_restriction] ${call_direction}(inbound) =~ /^(outbound)$/ break=on-false
  696. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->local_dids] continue=false
  697. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [local_dids] destination_number(4200) =~ /^\+?1?(\d{10})$/ break=on-false
  698. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->CanadaCalling] continue=false
  699. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [CanadaCalling] ${toll_allow}(all) =~ /all|canada|northamerica/ break=on-false
  700. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [CanadaCalling] destination_number(4200) =~ /^\+?1?((204|226|236|249|250|289|306|343|343|365|403|416|418|431|437|438|450|506|514|519|548|579|581|587|604|613|613|639|647|705|709|778|780|782|782|807|819|825|867|867|867|873|902|902|905)[2-9]\d{6})$/ break=on-false
  701. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->US_Calling] continue=false
  702. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [US_Calling] ${toll_allow}(all) =~ /all|northamerica|unitedstates/ break=on-false
  703. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [US_Calling] destination_number(4200) =~ /^\+?1?((201|202|203|205|206|207|208|209|210|212|213|214|215|216|217|218|219|220|224|225|227|228|229|231|234|239|240|248|251|252|253|254|256|260|262|267|269|270|272|274|276|281|283|301|302|303|304|305|307|308|309|310|312|313|314|315|316|317|318|319|320|321|323|325|327|330|331|332|334|336|337|339|346|347|351|352|360|361|364|380|385|386|401|402|404|405|406|407|408|409|410|412|413|414|415|417|419|423|424|425|430|432|434|435|440|442|443|447|458|463|464|469|470|475|478|479|480|484|501|502|503|504|505|507|508|509|510|512|513|515|516|517|518|520|530|531|534|539|540|541|551|557|559|561|562|563|564|567|570|571|573|574|575|580|585|586|601|602|603|605|606|607|608|609|610|612|614|615|616|617|618|619|620|623|626|628|629|630|631|636|641|646|650|651|657|659|660|661|662|667|669|678|679|680|681|682|689|701|702|703|704|706|707|708|712|713|714|715|716|717|718|719|720|724|725|726|727|730|731|732|734|737|740|743|747|754|757|760|762|763|765|769|770|772|773|774|775|779|781|785|786|801|802|803|804|805|806|808|810|812|813|814|815|816|817|818|828|830|831|832|838|843|845|847|848|850|854|856|857|858|859|860|862|863|864|865|870|872|878|901|903|904|906|907|908|909|910|912|913|914|915|916|917|918|919|920|925|928|929|930|931|934|936|937|938|940|941|947|949|951|952|954|956|959|970|971|972|973|975|978|979|980|984|985|986|989)[2-9]\d{6})$/ break=on-false
  704. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Toll-Free_Calling] continue=false
  705. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Toll-Free_Calling] destination_number(4200) =~ /^\+?1?(8(00|33|44|55|66|77|88)[2-9]\d{6})$/ break=on-false
  706. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->310-Iris] continue=false
  707. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [310-Iris] destination_number(4200) =~ /^310(\d{4})$/ break=on-false
  708. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Anguilla] continue=false
  709. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Anguilla] destination_number(4200) =~ /^\+?1?(264[2-9]\d{6})$/ break=on-false
  710. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Bermuda] continue=false
  711. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [Bermuda] ${toll_allow}(all) =~ /all|northamerica|bermuda/ break=on-false
  712. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Bermuda] destination_number(4200) =~ /^\+?1?(441[2-9]\d{6})$/ break=on-false
  713. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Jamaica] continue=false
  714. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Jamaica] destination_number(4200) =~ /^\+?1?(876[2-9]\d{6})$/ break=on-false
  715. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->agent_status] continue=false
  716. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [agent_status] destination_number(4200) =~ /^\*22$/ break=on-false
  717. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->International-LCR] continue=false
  718. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [International-LCR] ${toll_allow}(all) =~ /all|international|CAUSInternational/ break=on-false
  719. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [International-LCR] destination_number(4200) =~ /^(011|00)(\d{9,17})$/ break=on-false
  720. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->sbc.voxbeam.com.International-copy] continue=false
  721. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [sbc.voxbeam.com.International-copy] destination_number(4200) =~ /^(011|00)(\d{9,17})$/ break=on-false
  722. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->agent_status_id] continue=false
  723. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [agent_status_id] destination_number(4200) =~ /^\*23$/ break=on-false
  724. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->group-intercept] continue=false
  725. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [group-intercept] destination_number(4200) =~ /^\*8$/ break=on-false
  726. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->page-extension] continue=false
  727. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [page-extension] destination_number(4200) =~ /^\*8(\d{2,7})$/ break=on-false
  728. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->eavesdrop] continue=false
  729. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [eavesdrop] destination_number(4200) =~ /^\*33(\d{2,7})$/ break=on-false
  730. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_privacy] continue=false
  731. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_privacy] destination_number(4200) =~ /^\*67(\d+)$/ break=on-false
  732. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->call_return] continue=false
  733. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [call_return] destination_number(4200) =~ /^\*69$/ break=on-false
  734. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->extension_queue] continue=false
  735. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [extension_queue] destination_number(4200) =~ /^\*800(.*)$/ break=on-false
  736. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->intercept-ext] continue=false
  737. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [intercept-ext] destination_number(4200) =~ /^\*\*(\d+)$/ break=on-false
  738. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->intercept-ext-polycom] continue=false
  739. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [intercept-ext-polycom] destination_number(4200) =~ /^\*97(\d+)$/ break=on-false
  740. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->dx] continue=false
  741. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [dx] destination_number(4200) =~ /^dx$/ break=on-false
  742. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->no-ring] continue=true
  743. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [no-ring] destination_number(4200) =~ /^4000$/ break=on-false
  744. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->att_xfer] continue=false
  745. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [att_xfer] destination_number(4200) =~ /^att_xfer$/ break=on-false
  746. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->Carmel-Fax] continue=false
  747. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [Carmel-Fax] destination_number(4200) =~ /^125$/ break=on-false
  748. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->extension-to-voicemail] continue=false
  749. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
  750. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->send_to_voicemail] continue=false
  751. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [send_to_voicemail] destination_number(4200) =~ /^\*99(\d{2,10})$/ break=on-false
  752. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->vmain] continue=false
  753. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [vmain] destination_number(4200) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
  754. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->xfer_vm] continue=false
  755. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [xfer_vm] destination_number(4200) =~ /^xfer_vm$/ break=on-false
  756. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->is_transfer] continue=false
  757. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [is_transfer] destination_number(4200) =~ /^is_transfer$/ break=on-false
  758. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->vmain_user] continue=false
  759. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (FAIL) [vmain_user] destination_number(4200) =~ /^\*97$/ break=on-false
  760. Dialplan: sofia/external/5195752944@208.89.129.46 parsing [thecarmel.uphone.ca->allphones] continue=
  761. Dialplan: sofia/external/5195752944@208.89.129.46 Regex (PASS) [allphones] destination_number(4200) =~ /^4200$/ break=on-false
  762. Dialplan: sofia/external/5195752944@208.89.129.46 Action set(ring_group_uuid=fcb49e4a-3852-4652-b708-837db39d3afe)
  763. Dialplan: sofia/external/5195752944@208.89.129.46 Action lua(app.lua ring_groups)
  764. 2018-08-29 13:24:48.455922 [DEBUG] switch_core_state_machine.c:286 (sofia/external/5195752944@208.89.129.46) State Change CS_ROUTING -> CS_EXECUTE
  765. 2018-08-29 13:24:48.455922 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5195752944@208.89.129.46) State ROUTING going to sleep
  766. 2018-08-29 13:24:48.455922 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_EXECUTE (Cur 1 Tot 46967)
  767. 2018-08-29 13:24:48.455922 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE
  768. 2018-08-29 13:24:48.455922 [DEBUG] mod_sofia.c:198 sofia/external/5195752944@208.89.129.46 SOFIA EXECUTE
  769. 2018-08-29 13:24:48.455922 [DEBUG] switch_core_state_machine.c:328 sofia/external/5195752944@208.89.129.46 Standard EXECUTE
  770. EXECUTE sofia/external/5195752944@208.89.129.46 export(origination_callee_id_name=4200)
  771. 2018-08-29 13:24:48.455922 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[4200]
  772. EXECUTE sofia/external/5195752944@208.89.129.46 set(RFC2822_DATE=Wed, 29 Aug 2018 13:24:48 -0400)
  773. 2018-08-29 13:24:48.455922 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [RFC2822_DATE]=[Wed, 29 Aug 2018 13:24:48 -0400]
  774. EXECUTE sofia/external/5195752944@208.89.129.46 lua(app.lua call_block)
  775. 2018-08-29 13:24:48.455922 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('call_block', '')
  776. 2018-08-29 13:24:48.455922 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  777. 2018-08-29 13:24:48.455922 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  778. 2018-08-29 13:24:48.455922 [DEBUG] switch_cpp.cpp:1112 sofia/external/5195752944@208.89.129.46 destroy/unlink session from object
  779. EXECUTE sofia/external/5195752944@208.89.129.46 hash(insert/thecarmel.uphone.ca-last_dial/5195752944/4200)
  780. EXECUTE sofia/external/5195752944@208.89.129.46 set(ring_group_uuid=fcb49e4a-3852-4652-b708-837db39d3afe)
  781. 2018-08-29 13:24:48.455922 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [ring_group_uuid]=[fcb49e4a-3852-4652-b708-837db39d3afe]
  782. EXECUTE sofia/external/5195752944@208.89.129.46 lua(app.lua ring_groups)
  783. 2018-08-29 13:24:48.455922 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  784. 2018-08-29 13:24:48.575936 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  785. 2018-08-29 13:24:48.575936 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  786. EXECUTE sofia/external/5195752944@208.89.129.46 set(uuids=75894385-872f-4621-aa15-71d46af45bcc)
  787. 2018-08-29 13:24:48.575936 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [uuids]=[75894385-872f-4621-aa15-71d46af45bcc]
  788. EXECUTE sofia/external/5195752944@208.89.129.46 export(sip_h_Alert-Info=)
  789. 2018-08-29 13:24:48.575936 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  790. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_key=exec)
  791. 2018-08-29 13:24:48.575936 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_key]=[exec]
  792. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  793. 2018-08-29 13:24:48.575936 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  794. 2018-08-29 13:24:48.575936 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  795. 2018-08-29 13:24:48.575936 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  796. EXECUTE sofia/external/5195752944@208.89.129.46 set(uuids=75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d)
  797. 2018-08-29 13:24:48.575936 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [uuids]=[75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d]
  798. EXECUTE sofia/external/5195752944@208.89.129.46 export(sip_h_Alert-Info=)
  799. 2018-08-29 13:24:48.575936 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  800. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_key=exec)
  801. 2018-08-29 13:24:48.575936 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_key]=[exec]
  802. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  803. 2018-08-29 13:24:48.575936 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  804. 2018-08-29 13:24:48.595933 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  805. 2018-08-29 13:24:48.595933 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  806. EXECUTE sofia/external/5195752944@208.89.129.46 set(uuids=75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400)
  807. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [uuids]=[75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400]
  808. EXECUTE sofia/external/5195752944@208.89.129.46 export(sip_h_Alert-Info=)
  809. 2018-08-29 13:24:48.595933 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  810. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_key=exec)
  811. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_key]=[exec]
  812. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  813. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  814. 2018-08-29 13:24:48.595933 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  815. 2018-08-29 13:24:48.595933 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  816. EXECUTE sofia/external/5195752944@208.89.129.46 set(uuids=75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08)
  817. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [uuids]=[75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08]
  818. EXECUTE sofia/external/5195752944@208.89.129.46 export(sip_h_Alert-Info=)
  819. 2018-08-29 13:24:48.595933 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  820. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_key=exec)
  821. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_key]=[exec]
  822. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  823. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  824. 2018-08-29 13:24:48.595933 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  825. 2018-08-29 13:24:48.595933 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  826. EXECUTE sofia/external/5195752944@208.89.129.46 set(uuids=75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08,0bae4e82-bebd-4fe2-b783-6368158e7de5)
  827. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [uuids]=[75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08,0bae4e82-bebd-4fe2-b783-6368158e7de5]
  828. EXECUTE sofia/external/5195752944@208.89.129.46 export(sip_h_Alert-Info=)
  829. 2018-08-29 13:24:48.595933 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  830. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_key=exec)
  831. 2018-08-29 13:24:48.595933 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_key]=[exec]
  832. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  833. 2018-08-29 13:24:48.615928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  834. 2018-08-29 13:24:48.615928 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  835. 2018-08-29 13:24:48.615928 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  836. EXECUTE sofia/external/5195752944@208.89.129.46 set(uuids=75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08,0bae4e82-bebd-4fe2-b783-6368158e7de5,a9b26888-a3ac-40f6-b571-0d6cb07801df)
  837. 2018-08-29 13:24:48.615928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [uuids]=[75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08,0bae4e82-bebd-4fe2-b783-6368158e7de5,a9b26888-a3ac-40f6-b571-0d6cb07801df]
  838. EXECUTE sofia/external/5195752944@208.89.129.46 export(sip_h_Alert-Info=)
  839. 2018-08-29 13:24:48.615928 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  840. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_key=exec)
  841. 2018-08-29 13:24:48.615928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_key]=[exec]
  842. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  843. 2018-08-29 13:24:48.615928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  844. 2018-08-29 13:24:48.615928 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  845. 2018-08-29 13:24:48.615928 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1')
  846. EXECUTE sofia/external/5195752944@208.89.129.46 set(uuids=75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08,0bae4e82-bebd-4fe2-b783-6368158e7de5,a9b26888-a3ac-40f6-b571-0d6cb07801df,8ff411a9-882b-40ed-a1bf-d4c67b8a709b)
  847. 2018-08-29 13:24:48.615928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [uuids]=[75894385-872f-4621-aa15-71d46af45bcc,5a09592f-9ad7-4f24-9e8c-9a285cd6183d,522e73b7-dc56-4c15-98ce-eee6a3382400,0a4956f7-c7de-458a-b19e-e614e0368c08,0bae4e82-bebd-4fe2-b783-6368158e7de5,a9b26888-a3ac-40f6-b571-0d6cb07801df,8ff411a9-882b-40ed-a1bf-d4c67b8a709b]
  848. EXECUTE sofia/external/5195752944@208.89.129.46 export(sip_h_Alert-Info=)
  849. 2018-08-29 13:24:48.615928 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  850. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_key=exec)
  851. 2018-08-29 13:24:48.615928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_key]=[exec]
  852. EXECUTE sofia/external/5195752944@208.89.129.46 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  853. 2018-08-29 13:24:48.615928 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  854. EXECUTE sofia/external/5195752944@208.89.129.46 set(hangup_after_bridge=true)
  855. 2018-08-29 13:24:48.635932 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [hangup_after_bridge]=[true]
  856. EXECUTE sofia/external/5195752944@208.89.129.46 set(continue_on_fail=true)
  857. 2018-08-29 13:24:48.635932 [DEBUG] mod_dptools.c:1530 SET sofia/external/5195752944@208.89.129.46 [continue_on_fail]=[true]
  858. EXECUTE sofia/external/5195752944@208.89.129.46 bind_digit_action(local,*1,exec:execute_extension,dx XML thecarmel.uphone.ca,peer)
  859. 2018-08-29 13:24:48.635932 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'local'
  860. 2018-08-29 13:24:48.635932 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *1/local/0 callback: 0x7f678bb89956 data: 0x7f67900b5068
  861. EXECUTE sofia/external/5195752944@208.89.129.46 bind_digit_action(local,*2,exec:record_session,/var/lib/freeswitch/recordings/thecarmel.uphone.ca/archive/2018/Aug/29/be373d2a-b115-4ab2-8037-5a7b62a392cc.wav,peer)
  862. 2018-08-29 13:24:48.635932 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *2/local/0 callback: 0x7f678bb89956 data: 0x7f67900b5248
  863. EXECUTE sofia/external/5195752944@208.89.129.46 bind_digit_action(local,*3,exec:execute_extension,cf XML thecarmel.uphone.ca,peer)
  864. 2018-08-29 13:24:48.635932 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *3/local/0 callback: 0x7f678bb89956 data: 0x7f67900b53c8
  865. EXECUTE sofia/external/5195752944@208.89.129.46 bind_digit_action(local,*4,exec:execute_extension,att_xfer XML thecarmel.uphone.ca,peer)
  866. 2018-08-29 13:24:48.635932 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *4/local/0 callback: 0x7f678bb89956 data: 0x7f67900b5508
  867. EXECUTE sofia/external/5195752944@208.89.129.46 digit_action_set_realm(local)
  868. 2018-08-29 13:24:48.635932 [NOTICE] switch_cpp.cpp:1365 [ring group] app_data: {ignore_early_media=true}[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=100,extension_uuid=d0148c31-6763-47c7-8794-c57347a00265]user/100@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=101,extension_uuid=e2e517a9-2741-4661-8436-2d1ac9db76e2]user/101@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=105,extension_uuid=2c97355e-9afc-45c9-a550-ebd5a5dfcc23]user/105@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=106,extension_uuid=ead3420b-9f5a-4fdd-a865-b841306691d5]user/106@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=107,extension_uuid=21b0a948-6f17-40a5-ba85-dd5760636887]user/107@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=108,extension_uuid=aa0e1824-d818-46ef-8009-0f1eddf8e349]user/108@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=109,extension_uuid=94740196-e7c6-4695-b589-8b995e0b51fe]user/109@thecarmel.uphone.ca
  869. EXECUTE sofia/external/5195752944@208.89.129.46 bridge({ignore_early_media=true}[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=100,extension_uuid=d0148c31-6763-47c7-8794-c57347a00265]user/100@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=101,extension_uuid=e2e517a9-2741-4661-8436-2d1ac9db76e2]user/101@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=105,extension_uuid=2c97355e-9afc-45c9-a550-ebd5a5dfcc23]user/105@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=106,extension_uuid=ead3420b-9f5a-4fdd-a865-b841306691d5]user/106@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=107,extension_uuid=21b0a948-6f17-40a5-ba85-dd5760636887]user/107@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=108,extension_uuid=aa0e1824-d818-46ef-8009-0f1eddf8e349]user/108@thecarmel.uphone.ca,[sip_invite_domain=thecarmel.uphone.ca,call_direction=inbound,confirm=false,leg_timeout=25,leg_delay_start=0,dialed_extension=109,extension_uuid=94740196-e7c6-4695-b589-8b995e0b51fe]user/109@thecarmel.uphone.ca)
  870. 2018-08-29 13:24:48.635932 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  871. 2018-08-29 13:24:48.635932 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  872. 2018-08-29 13:24:48.635932 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  873. 2018-08-29 13:24:48.635932 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  874. 2018-08-29 13:24:48.635932 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  875. 2018-08-29 13:24:48.635932 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  876. 2018-08-29 13:24:48.635932 [DEBUG] switch_ivr_originate.c:2663 Parsing session specific variables
  877. 2018-08-29 13:24:48.635932 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  878. 2018-08-29 13:24:48.635932 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f679012a2c0 Connected.
  879. 2018-08-29 13:24:48.635932 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f679012a2c0 released.
  880. 2018-08-29 13:24:48.655930 [NOTICE] switch_cpp.cpp:1365 [xml_handler] local_hostname: voip-ss-001.uphone.ca database_hostname: voip-ss-002.uphone.ca dial_string: {sip_h_X-context=thecarmel.uphone.ca,sip_invite_domain=thecarmel.uphone.ca,presence_id=100@thecarmel.uphone.ca}sofia/internal/100@thecarmel.uphone.ca;fs_path=sip:voip-ss-002.uphone.ca
  881. 2018-08-29 13:24:48.655930 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  882. 2018-08-29 13:24:48.655930 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  883. 2018-08-29 13:24:48.655930 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  884. 2018-08-29 13:24:48.655930 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  885. 2018-08-29 13:24:48.655930 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  886. 2018-08-29 13:24:48.655930 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  887. 2018-08-29 13:24:48.655930 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  888. 2018-08-29 13:24:48.655930 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/100@thecarmel.uphone.ca [8a155f8b-bd48-443e-9783-74781ff86da0]
  889. 2018-08-29 13:24:48.655930 [DEBUG] mod_sofia.c:4818 (sofia/internal/100@thecarmel.uphone.ca) State Change CS_NEW -> CS_INIT
  890. 2018-08-29 13:24:48.655930 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/100@thecarmel.uphone.ca Setting leg timeout to 25
  891. 2018-08-29 13:24:48.655930 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/100@thecarmel.uphone.ca Setting leg timeout to 25
  892. 2018-08-29 13:24:48.655930 [DEBUG] switch_ivr_originate.c:2663 Parsing session specific variables
  893. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@thecarmel.uphone.ca) Running State Change CS_INIT (Cur 2 Tot 46968)
  894. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100@thecarmel.uphone.ca) State INIT
  895. 2018-08-29 13:24:48.655930 [DEBUG] mod_sofia.c:90 sofia/internal/100@thecarmel.uphone.ca SOFIA INIT
  896. 2018-08-29 13:24:48.655930 [DEBUG] sofia_glue.c:1264 sip:voip-ss-002.uphone.ca Setting proxy route to sofia/internal/100@thecarmel.uphone.ca
  897. 2018-08-29 13:24:48.655930 [DEBUG] sofia_glue.c:1295 sofia/internal/100@thecarmel.uphone.ca sending invite version: 1.6.16  64bit
  898. Local SDP:
  899. v=0
  900. o=FreeSWITCH 1535533444 1535533445 IN IP4 208.90.97.6
  901. s=FreeSWITCH
  902. c=IN IP4 208.90.97.6
  903. t=0 0
  904. m=audio 30044 RTP/AVP 0 8 18 3 102 101 13 103 104
  905. a=rtpmap:0 PCMU/8000
  906. a=rtpmap:8 PCMA/8000
  907. a=rtpmap:18 G729/8000
  908. a=fmtp:18 annexb=no
  909. a=rtpmap:3 GSM/8000
  910. a=rtpmap:102 opus/48000/2
  911. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  912. a=rtpmap:101 telephone-event/8000
  913. a=fmtp:101 0-16
  914. a=rtpmap:103 telephone-event/48000
  915. a=fmtp:103 0-16
  916. a=rtcp-mux
  917. a=rtcp:30044 IN IP4 208.90.97.6
  918. a=rtpmap:13 CN/8000
  919. a=rtpmap:104 CN/48000
  920. a=ptime:20
  921. a=sendrecv
  922.  
  923. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:40 sofia/internal/100@thecarmel.uphone.ca Standard INIT
  924. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/100@thecarmel.uphone.ca) State Change CS_INIT -> CS_ROUTING
  925. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100@thecarmel.uphone.ca) State INIT going to sleep
  926. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@thecarmel.uphone.ca) Running State Change CS_ROUTING (Cur 2 Tot 46968)
  927. 2018-08-29 13:24:48.655930 [DEBUG] sofia.c:7048 Channel sofia/internal/100@thecarmel.uphone.ca entering state [calling][0]
  928. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100@thecarmel.uphone.ca) State ROUTING
  929. 2018-08-29 13:24:48.655930 [DEBUG] mod_sofia.c:143 sofia/internal/100@thecarmel.uphone.ca SOFIA ROUTING
  930. 2018-08-29 13:24:48.655930 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/100@thecarmel.uphone.ca) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  931. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100@thecarmel.uphone.ca) State ROUTING going to sleep
  932. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@thecarmel.uphone.ca) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 46968)
  933. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/100@thecarmel.uphone.ca) State CONSUME_MEDIA
  934. 2018-08-29 13:24:48.655930 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/100@thecarmel.uphone.ca) State CONSUME_MEDIA going to sleep
  935. 2018-08-29 13:24:48.655930 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  936. 2018-08-29 13:24:48.655930 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f679012a2c0 Connected.
  937. 2018-08-29 13:24:48.655930 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f679012a2c0 released.
  938. 2018-08-29 13:24:48.675926 [NOTICE] switch_cpp.cpp:1365 [xml_handler] local_hostname: voip-ss-001.uphone.ca database_hostname: voip-ss-002.uphone.ca dial_string: {sip_h_X-context=thecarmel.uphone.ca,sip_invite_domain=thecarmel.uphone.ca,presence_id=101@thecarmel.uphone.ca}sofia/internal/101@thecarmel.uphone.ca;fs_path=sip:voip-ss-002.uphone.ca
  939. 2018-08-29 13:24:48.675926 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  940. 2018-08-29 13:24:48.675926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  941. 2018-08-29 13:24:48.675926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  942. 2018-08-29 13:24:48.675926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  943. 2018-08-29 13:24:48.675926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  944. 2018-08-29 13:24:48.675926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  945. 2018-08-29 13:24:48.675926 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  946. 2018-08-29 13:24:48.675926 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/101@thecarmel.uphone.ca [7f659e37-b123-4f5d-9731-cd4e9c911107]
  947. 2018-08-29 13:24:48.675926 [DEBUG] mod_sofia.c:4818 (sofia/internal/101@thecarmel.uphone.ca) State Change CS_NEW -> CS_INIT
  948. 2018-08-29 13:24:48.675926 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/101@thecarmel.uphone.ca Setting leg timeout to 25
  949. 2018-08-29 13:24:48.675926 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/101@thecarmel.uphone.ca Setting leg timeout to 25
  950. 2018-08-29 13:24:48.675926 [DEBUG] switch_ivr_originate.c:2663 Parsing session specific variables
  951. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@thecarmel.uphone.ca) Running State Change CS_INIT (Cur 3 Tot 46969)
  952. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/101@thecarmel.uphone.ca) State INIT
  953. 2018-08-29 13:24:48.675926 [DEBUG] mod_sofia.c:90 sofia/internal/101@thecarmel.uphone.ca SOFIA INIT
  954. 2018-08-29 13:24:48.675926 [DEBUG] sofia_glue.c:1264 sip:voip-ss-002.uphone.ca Setting proxy route to sofia/internal/101@thecarmel.uphone.ca
  955. 2018-08-29 13:24:48.675926 [DEBUG] sofia_glue.c:1295 sofia/internal/101@thecarmel.uphone.ca sending invite version: 1.6.16  64bit
  956. Local SDP:
  957. v=0
  958. o=FreeSWITCH 1535540964 1535540965 IN IP4 208.90.97.6
  959. s=FreeSWITCH
  960. c=IN IP4 208.90.97.6
  961. t=0 0
  962. m=audio 22524 RTP/AVP 0 8 18 3 102 101 13 103 104
  963. a=rtpmap:0 PCMU/8000
  964. a=rtpmap:8 PCMA/8000
  965. a=rtpmap:18 G729/8000
  966. a=fmtp:18 annexb=no
  967. a=rtpmap:3 GSM/8000
  968. a=rtpmap:102 opus/48000/2
  969. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  970. a=rtpmap:101 telephone-event/8000
  971. a=fmtp:101 0-16
  972. a=rtpmap:103 telephone-event/48000
  973. a=fmtp:103 0-16
  974. a=rtcp-mux
  975. a=rtcp:22524 IN IP4 208.90.97.6
  976. a=rtpmap:13 CN/8000
  977. a=rtpmap:104 CN/48000
  978. a=ptime:20
  979. a=sendrecv
  980.  
  981. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:40 sofia/internal/101@thecarmel.uphone.ca Standard INIT
  982. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/101@thecarmel.uphone.ca) State Change CS_INIT -> CS_ROUTING
  983. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/101@thecarmel.uphone.ca) State INIT going to sleep
  984. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@thecarmel.uphone.ca) Running State Change CS_ROUTING (Cur 3 Tot 46969)
  985. 2018-08-29 13:24:48.675926 [DEBUG] sofia.c:7048 Channel sofia/internal/101@thecarmel.uphone.ca entering state [calling][0]
  986. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/101@thecarmel.uphone.ca) State ROUTING
  987. 2018-08-29 13:24:48.675926 [DEBUG] mod_sofia.c:143 sofia/internal/101@thecarmel.uphone.ca SOFIA ROUTING
  988. 2018-08-29 13:24:48.675926 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/101@thecarmel.uphone.ca) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  989. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/101@thecarmel.uphone.ca) State ROUTING going to sleep
  990. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@thecarmel.uphone.ca) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 46969)
  991. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/101@thecarmel.uphone.ca) State CONSUME_MEDIA
  992. 2018-08-29 13:24:48.675926 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/101@thecarmel.uphone.ca) State CONSUME_MEDIA going to sleep
  993. 2018-08-29 13:24:48.675926 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  994. 2018-08-29 13:24:48.675926 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f679012a2c0 Connected.
  995. 2018-08-29 13:24:48.675926 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f679012a2c0 released.
  996. 2018-08-29 13:24:48.675926 [NOTICE] switch_cpp.cpp:1365 [xml_handler] local_hostname: voip-ss-001.uphone.ca database_hostname: voip-ss-002.uphone.ca dial_string: {sip_h_X-context=thecarmel.uphone.ca,sip_invite_domain=thecarmel.uphone.ca,presence_id=105@thecarmel.uphone.ca}sofia/internal/105@thecarmel.uphone.ca;fs_path=sip:voip-ss-002.uphone.ca
  997. 2018-08-29 13:24:48.695926 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  998. 2018-08-29 13:24:48.695926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  999. 2018-08-29 13:24:48.695926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  1000. 2018-08-29 13:24:48.695926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1001. 2018-08-29 13:24:48.695926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1002. 2018-08-29 13:24:48.695926 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1003. 2018-08-29 13:24:48.695926 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1004. 2018-08-29 13:24:48.695926 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/105@thecarmel.uphone.ca [51dd5bcf-68c7-4b70-b70d-4d5d6ee23273]
  1005. 2018-08-29 13:24:48.695926 [DEBUG] mod_sofia.c:4818 (sofia/internal/105@thecarmel.uphone.ca) State Change CS_NEW -> CS_INIT
  1006. 2018-08-29 13:24:48.695926 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/105@thecarmel.uphone.ca Setting leg timeout to 25
  1007. 2018-08-29 13:24:48.695926 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/105@thecarmel.uphone.ca Setting leg timeout to 25
  1008. 2018-08-29 13:24:48.695926 [DEBUG] switch_ivr_originate.c:2663 Parsing session specific variables
  1009. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/105@thecarmel.uphone.ca) Running State Change CS_INIT (Cur 4 Tot 46970)
  1010. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/105@thecarmel.uphone.ca) State INIT
  1011. 2018-08-29 13:24:48.695926 [DEBUG] mod_sofia.c:90 sofia/internal/105@thecarmel.uphone.ca SOFIA INIT
  1012. 2018-08-29 13:24:48.695926 [DEBUG] sofia_glue.c:1264 sip:voip-ss-002.uphone.ca Setting proxy route to sofia/internal/105@thecarmel.uphone.ca
  1013. 2018-08-29 13:24:48.695926 [DEBUG] sofia_glue.c:1295 sofia/internal/105@thecarmel.uphone.ca sending invite version: 1.6.16  64bit
  1014. Local SDP:
  1015. v=0
  1016. o=FreeSWITCH 1535530884 1535530885 IN IP4 208.90.97.6
  1017. s=FreeSWITCH
  1018. c=IN IP4 208.90.97.6
  1019. t=0 0
  1020. m=audio 32604 RTP/AVP 0 8 18 3 102 101 13 103 104
  1021. a=rtpmap:0 PCMU/8000
  1022. a=rtpmap:8 PCMA/8000
  1023. a=rtpmap:18 G729/8000
  1024. a=fmtp:18 annexb=no
  1025. a=rtpmap:3 GSM/8000
  1026. a=rtpmap:102 opus/48000/2
  1027. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  1028. a=rtpmap:101 telephone-event/8000
  1029. a=fmtp:101 0-16
  1030. a=rtpmap:103 telephone-event/48000
  1031. a=fmtp:103 0-16
  1032. a=rtcp-mux
  1033. a=rtcp:32604 IN IP4 208.90.97.6
  1034. a=rtpmap:13 CN/8000
  1035. a=rtpmap:104 CN/48000
  1036. a=ptime:20
  1037. a=sendrecv
  1038.  
  1039. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:40 sofia/internal/105@thecarmel.uphone.ca Standard INIT
  1040. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/105@thecarmel.uphone.ca) State Change CS_INIT -> CS_ROUTING
  1041. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/105@thecarmel.uphone.ca) State INIT going to sleep
  1042. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/105@thecarmel.uphone.ca) Running State Change CS_ROUTING (Cur 4 Tot 46970)
  1043. 2018-08-29 13:24:48.695926 [DEBUG] sofia.c:7048 Channel sofia/internal/105@thecarmel.uphone.ca entering state [calling][0]
  1044. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/105@thecarmel.uphone.ca) State ROUTING
  1045. 2018-08-29 13:24:48.695926 [DEBUG] mod_sofia.c:143 sofia/internal/105@thecarmel.uphone.ca SOFIA ROUTING
  1046. 2018-08-29 13:24:48.695926 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/105@thecarmel.uphone.ca) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1047. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/105@thecarmel.uphone.ca) State ROUTING going to sleep
  1048. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/105@thecarmel.uphone.ca) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 46970)
  1049. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/105@thecarmel.uphone.ca) State CONSUME_MEDIA
  1050. 2018-08-29 13:24:48.695926 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/105@thecarmel.uphone.ca) State CONSUME_MEDIA going to sleep
  1051. 2018-08-29 13:24:48.695926 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  1052. 2018-08-29 13:24:48.695926 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f679012a2c0 Connected.
  1053. 2018-08-29 13:24:48.695926 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f679012a2c0 released.
  1054. 2018-08-29 13:24:48.695926 [NOTICE] switch_cpp.cpp:1365 [xml_handler] local_hostname: voip-ss-001.uphone.ca database_hostname: voip-ss-002.uphone.ca dial_string: {sip_h_X-context=thecarmel.uphone.ca,sip_invite_domain=thecarmel.uphone.ca,presence_id=106@thecarmel.uphone.ca}sofia/internal/106@thecarmel.uphone.ca;fs_path=sip:voip-ss-002.uphone.ca
  1055. 2018-08-29 13:24:48.715929 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  1056. 2018-08-29 13:24:48.715929 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  1057. 2018-08-29 13:24:48.715929 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  1058. 2018-08-29 13:24:48.715929 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1059. 2018-08-29 13:24:48.715929 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1060. 2018-08-29 13:24:48.715929 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1061. 2018-08-29 13:24:48.715929 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1062. 2018-08-29 13:24:48.715929 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/106@thecarmel.uphone.ca [ec5cab3e-d4ba-474f-a3ff-211ecd2e2626]
  1063. 2018-08-29 13:24:48.715929 [DEBUG] mod_sofia.c:4818 (sofia/internal/106@thecarmel.uphone.ca) State Change CS_NEW -> CS_INIT
  1064. 2018-08-29 13:24:48.715929 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/106@thecarmel.uphone.ca Setting leg timeout to 25
  1065. 2018-08-29 13:24:48.715929 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/106@thecarmel.uphone.ca Setting leg timeout to 25
  1066. 2018-08-29 13:24:48.715929 [DEBUG] switch_ivr_originate.c:2663 Parsing session specific variables
  1067. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@thecarmel.uphone.ca) Running State Change CS_INIT (Cur 5 Tot 46971)
  1068. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/106@thecarmel.uphone.ca) State INIT
  1069. 2018-08-29 13:24:48.715929 [DEBUG] mod_sofia.c:90 sofia/internal/106@thecarmel.uphone.ca SOFIA INIT
  1070. 2018-08-29 13:24:48.715929 [DEBUG] sofia_glue.c:1264 sip:voip-ss-002.uphone.ca Setting proxy route to sofia/internal/106@thecarmel.uphone.ca
  1071. 2018-08-29 13:24:48.715929 [DEBUG] sofia_glue.c:1295 sofia/internal/106@thecarmel.uphone.ca sending invite version: 1.6.16  64bit
  1072. Local SDP:
  1073. v=0
  1074. o=FreeSWITCH 1535546952 1535546953 IN IP4 208.90.97.6
  1075. s=FreeSWITCH
  1076. c=IN IP4 208.90.97.6
  1077. t=0 0
  1078. m=audio 16536 RTP/AVP 0 8 18 3 102 101 13 103 104
  1079. a=rtpmap:0 PCMU/8000
  1080. a=rtpmap:8 PCMA/8000
  1081. a=rtpmap:18 G729/8000
  1082. a=fmtp:18 annexb=no
  1083. a=rtpmap:3 GSM/8000
  1084. a=rtpmap:102 opus/48000/2
  1085. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  1086. a=rtpmap:101 telephone-event/8000
  1087. a=fmtp:101 0-16
  1088. a=rtpmap:103 telephone-event/48000
  1089. a=fmtp:103 0-16
  1090. a=rtcp-mux
  1091. a=rtcp:16536 IN IP4 208.90.97.6
  1092. a=rtpmap:13 CN/8000
  1093. a=rtpmap:104 CN/48000
  1094. a=ptime:20
  1095. a=sendrecv
  1096.  
  1097. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:40 sofia/internal/106@thecarmel.uphone.ca Standard INIT
  1098. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/106@thecarmel.uphone.ca) State Change CS_INIT -> CS_ROUTING
  1099. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/106@thecarmel.uphone.ca) State INIT going to sleep
  1100. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@thecarmel.uphone.ca) Running State Change CS_ROUTING (Cur 5 Tot 46971)
  1101. 2018-08-29 13:24:48.715929 [DEBUG] sofia.c:7048 Channel sofia/internal/106@thecarmel.uphone.ca entering state [calling][0]
  1102. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/106@thecarmel.uphone.ca) State ROUTING
  1103. 2018-08-29 13:24:48.715929 [DEBUG] mod_sofia.c:143 sofia/internal/106@thecarmel.uphone.ca SOFIA ROUTING
  1104. 2018-08-29 13:24:48.715929 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/106@thecarmel.uphone.ca) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1105. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/106@thecarmel.uphone.ca) State ROUTING going to sleep
  1106. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@thecarmel.uphone.ca) Running State Change CS_CONSUME_MEDIA (Cur 5 Tot 46971)
  1107. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/106@thecarmel.uphone.ca) State CONSUME_MEDIA
  1108. 2018-08-29 13:24:48.715929 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/106@thecarmel.uphone.ca) State CONSUME_MEDIA going to sleep
  1109. 2018-08-29 13:24:48.715929 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  1110. 2018-08-29 13:24:48.715929 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f679012a2c0 Connected.
  1111. 2018-08-29 13:24:48.715929 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f679012a2c0 released.
  1112. 2018-08-29 13:24:48.735931 [NOTICE] switch_cpp.cpp:1365 [xml_handler] local_hostname: voip-ss-001.uphone.ca database_hostname: voip-ss-002.uphone.ca dial_string: {sip_h_X-context=thecarmel.uphone.ca,sip_invite_domain=thecarmel.uphone.ca,presence_id=107@thecarmel.uphone.ca}sofia/internal/107@thecarmel.uphone.ca;fs_path=sip:voip-ss-002.uphone.ca
  1113. 2018-08-29 13:24:48.735931 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  1114. 2018-08-29 13:24:48.735931 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  1115. 2018-08-29 13:24:48.735931 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  1116. 2018-08-29 13:24:48.735931 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1117. 2018-08-29 13:24:48.735931 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1118. 2018-08-29 13:24:48.735931 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1119. 2018-08-29 13:24:48.735931 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1120. 2018-08-29 13:24:48.735931 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/107@thecarmel.uphone.ca [0aebce17-2c7c-44a6-bfc8-e4b2668a496c]
  1121. 2018-08-29 13:24:48.735931 [DEBUG] mod_sofia.c:4818 (sofia/internal/107@thecarmel.uphone.ca) State Change CS_NEW -> CS_INIT
  1122. 2018-08-29 13:24:48.735931 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/107@thecarmel.uphone.ca Setting leg timeout to 25
  1123. 2018-08-29 13:24:48.735931 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/107@thecarmel.uphone.ca Setting leg timeout to 25
  1124. 2018-08-29 13:24:48.735931 [DEBUG] switch_ivr_originate.c:2663 Parsing session specific variables
  1125. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/107@thecarmel.uphone.ca) Running State Change CS_INIT (Cur 6 Tot 46972)
  1126. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/107@thecarmel.uphone.ca) State INIT
  1127. 2018-08-29 13:24:48.735931 [DEBUG] mod_sofia.c:90 sofia/internal/107@thecarmel.uphone.ca SOFIA INIT
  1128. 2018-08-29 13:24:48.735931 [DEBUG] sofia_glue.c:1264 sip:voip-ss-002.uphone.ca Setting proxy route to sofia/internal/107@thecarmel.uphone.ca
  1129. 2018-08-29 13:24:48.735931 [DEBUG] sofia_glue.c:1295 sofia/internal/107@thecarmel.uphone.ca sending invite version: 1.6.16  64bit
  1130. Local SDP:
  1131. v=0
  1132. o=FreeSWITCH 1535532006 1535532007 IN IP4 208.90.97.6
  1133. s=FreeSWITCH
  1134. c=IN IP4 208.90.97.6
  1135. t=0 0
  1136. m=audio 31482 RTP/AVP 0 8 18 3 102 101 13 103 104
  1137. a=rtpmap:0 PCMU/8000
  1138. a=rtpmap:8 PCMA/8000
  1139. a=rtpmap:18 G729/8000
  1140. a=fmtp:18 annexb=no
  1141. a=rtpmap:3 GSM/8000
  1142. a=rtpmap:102 opus/48000/2
  1143. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  1144. a=rtpmap:101 telephone-event/8000
  1145. a=fmtp:101 0-16
  1146. a=rtpmap:103 telephone-event/48000
  1147. a=fmtp:103 0-16
  1148. a=rtcp-mux
  1149. a=rtcp:31482 IN IP4 208.90.97.6
  1150. a=rtpmap:13 CN/8000
  1151. a=rtpmap:104 CN/48000
  1152. a=ptime:20
  1153. a=sendrecv
  1154.  
  1155. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:40 sofia/internal/107@thecarmel.uphone.ca Standard INIT
  1156. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/107@thecarmel.uphone.ca) State Change CS_INIT -> CS_ROUTING
  1157. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/107@thecarmel.uphone.ca) State INIT going to sleep
  1158. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/107@thecarmel.uphone.ca) Running State Change CS_ROUTING (Cur 6 Tot 46972)
  1159. 2018-08-29 13:24:48.735931 [DEBUG] sofia.c:7048 Channel sofia/internal/107@thecarmel.uphone.ca entering state [calling][0]
  1160. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/107@thecarmel.uphone.ca) State ROUTING
  1161. 2018-08-29 13:24:48.735931 [DEBUG] mod_sofia.c:143 sofia/internal/107@thecarmel.uphone.ca SOFIA ROUTING
  1162. 2018-08-29 13:24:48.735931 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/107@thecarmel.uphone.ca) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1163. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/107@thecarmel.uphone.ca) State ROUTING going to sleep
  1164. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/107@thecarmel.uphone.ca) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 46972)
  1165. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/107@thecarmel.uphone.ca) State CONSUME_MEDIA
  1166. 2018-08-29 13:24:48.735931 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/107@thecarmel.uphone.ca) State CONSUME_MEDIA going to sleep
  1167. 2018-08-29 13:24:48.735931 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  1168. 2018-08-29 13:24:48.735931 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f679012a2c0 Connected.
  1169. 2018-08-29 13:24:48.755928 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f679012a2c0 released.
  1170. 2018-08-29 13:24:48.755928 [NOTICE] switch_cpp.cpp:1365 [xml_handler] local_hostname: voip-ss-001.uphone.ca database_hostname: voip-ss-002.uphone.ca dial_string: {sip_h_X-context=thecarmel.uphone.ca,sip_invite_domain=thecarmel.uphone.ca,presence_id=108@thecarmel.uphone.ca}sofia/internal/108@thecarmel.uphone.ca;fs_path=sip:voip-ss-002.uphone.ca
  1171. 2018-08-29 13:24:48.755928 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  1172. 2018-08-29 13:24:48.755928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  1173. 2018-08-29 13:24:48.755928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  1174. 2018-08-29 13:24:48.755928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1175. 2018-08-29 13:24:48.755928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1176. 2018-08-29 13:24:48.755928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1177. 2018-08-29 13:24:48.755928 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1178. 2018-08-29 13:24:48.755928 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/108@thecarmel.uphone.ca [fb4695bb-d44f-49bf-9e68-5a2a35dedc2e]
  1179. 2018-08-29 13:24:48.755928 [DEBUG] mod_sofia.c:4818 (sofia/internal/108@thecarmel.uphone.ca) State Change CS_NEW -> CS_INIT
  1180. 2018-08-29 13:24:48.755928 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/108@thecarmel.uphone.ca Setting leg timeout to 25
  1181. 2018-08-29 13:24:48.755928 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/108@thecarmel.uphone.ca Setting leg timeout to 25
  1182. 2018-08-29 13:24:48.755928 [DEBUG] switch_ivr_originate.c:2663 Parsing session specific variables
  1183. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/108@thecarmel.uphone.ca) Running State Change CS_INIT (Cur 7 Tot 46973)
  1184. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/108@thecarmel.uphone.ca) State INIT
  1185. 2018-08-29 13:24:48.755928 [DEBUG] mod_sofia.c:90 sofia/internal/108@thecarmel.uphone.ca SOFIA INIT
  1186. 2018-08-29 13:24:48.755928 [DEBUG] sofia_glue.c:1264 sip:voip-ss-002.uphone.ca Setting proxy route to sofia/internal/108@thecarmel.uphone.ca
  1187. 2018-08-29 13:24:48.755928 [DEBUG] sofia_glue.c:1295 sofia/internal/108@thecarmel.uphone.ca sending invite version: 1.6.16  64bit
  1188. Local SDP:
  1189. v=0
  1190. o=FreeSWITCH 1535538928 1535538929 IN IP4 208.90.97.6
  1191. s=FreeSWITCH
  1192. c=IN IP4 208.90.97.6
  1193. t=0 0
  1194. m=audio 24560 RTP/AVP 0 8 18 3 102 101 13 103 104
  1195. a=rtpmap:0 PCMU/8000
  1196. a=rtpmap:8 PCMA/8000
  1197. a=rtpmap:18 G729/8000
  1198. a=fmtp:18 annexb=no
  1199. a=rtpmap:3 GSM/8000
  1200. a=rtpmap:102 opus/48000/2
  1201. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  1202. a=rtpmap:101 telephone-event/8000
  1203. a=fmtp:101 0-16
  1204. a=rtpmap:103 telephone-event/48000
  1205. a=fmtp:103 0-16
  1206. a=rtcp-mux
  1207. a=rtcp:24560 IN IP4 208.90.97.6
  1208. a=rtpmap:13 CN/8000
  1209. a=rtpmap:104 CN/48000
  1210. a=ptime:20
  1211. a=sendrecv
  1212.  
  1213. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:40 sofia/internal/108@thecarmel.uphone.ca Standard INIT
  1214. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/108@thecarmel.uphone.ca) State Change CS_INIT -> CS_ROUTING
  1215. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/108@thecarmel.uphone.ca) State INIT going to sleep
  1216. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/108@thecarmel.uphone.ca) Running State Change CS_ROUTING (Cur 7 Tot 46973)
  1217. 2018-08-29 13:24:48.755928 [DEBUG] sofia.c:7048 Channel sofia/internal/108@thecarmel.uphone.ca entering state [calling][0]
  1218. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/108@thecarmel.uphone.ca) State ROUTING
  1219. 2018-08-29 13:24:48.755928 [DEBUG] mod_sofia.c:143 sofia/internal/108@thecarmel.uphone.ca SOFIA ROUTING
  1220. 2018-08-29 13:24:48.755928 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/108@thecarmel.uphone.ca) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1221. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/108@thecarmel.uphone.ca) State ROUTING going to sleep
  1222. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/108@thecarmel.uphone.ca) Running State Change CS_CONSUME_MEDIA (Cur 7 Tot 46973)
  1223. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/108@thecarmel.uphone.ca) State CONSUME_MEDIA
  1224. 2018-08-29 13:24:48.755928 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/108@thecarmel.uphone.ca) State CONSUME_MEDIA going to sleep
  1225. 2018-08-29 13:24:48.755928 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f67a409d500 Connected.
  1226. 2018-08-29 13:24:48.755928 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f679012a2c0 Connected.
  1227. 2018-08-29 13:24:48.775928 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f679012a2c0 released.
  1228. 2018-08-29 13:24:48.775928 [NOTICE] switch_cpp.cpp:1365 [xml_handler] local_hostname: voip-ss-001.uphone.ca database_hostname: voip-ss-002.uphone.ca dial_string: {sip_h_X-context=thecarmel.uphone.ca,sip_invite_domain=thecarmel.uphone.ca,presence_id=109@thecarmel.uphone.ca}sofia/internal/109@thecarmel.uphone.ca;fs_path=sip:voip-ss-002.uphone.ca
  1229. 2018-08-29 13:24:48.775928 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  1230. 2018-08-29 13:24:48.775928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [effective_caller_id_name]=[Adrien Alexson] to event
  1231. 2018-08-29 13:24:48.775928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [caller_id_name]=[Adrien Alexson] to event
  1232. 2018-08-29 13:24:48.775928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1233. 2018-08-29 13:24:48.775928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1234. 2018-08-29 13:24:48.775928 [DEBUG] switch_channel.c:1250 sofia/external/5195752944@208.89.129.46 EXPORTING[export_vars] [origination_callee_id_name]=[4200] to event
  1235. 2018-08-29 13:24:48.775928 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1236. 2018-08-29 13:24:48.775928 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/109@thecarmel.uphone.ca [99509b40-b4e4-4c53-a9cb-b1daf622751e]
  1237. 2018-08-29 13:24:48.775928 [DEBUG] mod_sofia.c:4818 (sofia/internal/109@thecarmel.uphone.ca) State Change CS_NEW -> CS_INIT
  1238. 2018-08-29 13:24:48.775928 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/109@thecarmel.uphone.ca Setting leg timeout to 25
  1239. 2018-08-29 13:24:48.775928 [DEBUG] switch_ivr_originate.c:2957 sofia/internal/109@thecarmel.uphone.ca Setting leg timeout to 25
  1240. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/109@thecarmel.uphone.ca) Running State Change CS_INIT (Cur 8 Tot 46974)
  1241. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/109@thecarmel.uphone.ca) State INIT
  1242. 2018-08-29 13:24:48.775928 [DEBUG] mod_sofia.c:90 sofia/internal/109@thecarmel.uphone.ca SOFIA INIT
  1243. 2018-08-29 13:24:48.775928 [DEBUG] sofia_glue.c:1264 sip:voip-ss-002.uphone.ca Setting proxy route to sofia/internal/109@thecarmel.uphone.ca
  1244. 2018-08-29 13:24:48.775928 [DEBUG] sofia_glue.c:1295 sofia/internal/109@thecarmel.uphone.ca sending invite version: 1.6.16  64bit
  1245. Local SDP:
  1246. v=0
  1247. o=FreeSWITCH 1535543610 1535543611 IN IP4 208.90.97.6
  1248. s=FreeSWITCH
  1249. c=IN IP4 208.90.97.6
  1250. t=0 0
  1251. m=audio 19878 RTP/AVP 0 8 18 3 102 101 13 103 104
  1252. a=rtpmap:0 PCMU/8000
  1253. a=rtpmap:8 PCMA/8000
  1254. a=rtpmap:18 G729/8000
  1255. a=fmtp:18 annexb=no
  1256. a=rtpmap:3 GSM/8000
  1257. a=rtpmap:102 opus/48000/2
  1258. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  1259. a=rtpmap:101 telephone-event/8000
  1260. a=fmtp:101 0-16
  1261. a=rtpmap:103 telephone-event/48000
  1262. a=fmtp:103 0-16
  1263. a=rtcp-mux
  1264. a=rtcp:19878 IN IP4 208.90.97.6
  1265. a=rtpmap:13 CN/8000
  1266. a=rtpmap:104 CN/48000
  1267. a=ptime:20
  1268. a=sendrecv
  1269.  
  1270. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:40 sofia/internal/109@thecarmel.uphone.ca Standard INIT
  1271. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/109@thecarmel.uphone.ca) State Change CS_INIT -> CS_ROUTING
  1272. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/109@thecarmel.uphone.ca) State INIT going to sleep
  1273. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/109@thecarmel.uphone.ca) Running State Change CS_ROUTING (Cur 8 Tot 46974)
  1274. 2018-08-29 13:24:48.775928 [DEBUG] sofia.c:7048 Channel sofia/internal/109@thecarmel.uphone.ca entering state [calling][0]
  1275. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/109@thecarmel.uphone.ca) State ROUTING
  1276. 2018-08-29 13:24:48.775928 [DEBUG] mod_sofia.c:143 sofia/internal/109@thecarmel.uphone.ca SOFIA ROUTING
  1277. 2018-08-29 13:24:48.775928 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/109@thecarmel.uphone.ca) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1278. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/109@thecarmel.uphone.ca) State ROUTING going to sleep
  1279. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/109@thecarmel.uphone.ca) Running State Change CS_CONSUME_MEDIA (Cur 8 Tot 46974)
  1280. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/109@thecarmel.uphone.ca) State CONSUME_MEDIA
  1281. 2018-08-29 13:24:48.775928 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/109@thecarmel.uphone.ca) State CONSUME_MEDIA going to sleep
  1282. 2018-08-29 13:24:49.236044 [INFO] sofia.c:1279 sofia/internal/101@thecarmel.uphone.ca Update Callee ID to "101" <sip:101@thecarmel.uphone.ca>
  1283. 2018-08-29 13:24:49.236044 [DEBUG] sofia.c:7048 Channel sofia/internal/101@thecarmel.uphone.ca entering state [proceeding][183]
  1284. 2018-08-29 13:24:49.236044 [DEBUG] sofia.c:7058 Remote SDP:
  1285. v=0
  1286. o=FreeSWITCH 1535536337 1535536338 IN IP4 208.90.97.9
  1287. s=FreeSWITCH
  1288. c=IN IP4 208.90.97.9
  1289. t=0 0
  1290. m=audio 27152 RTP/AVP 0 101 13
  1291. a=rtpmap:0 PCMU/8000
  1292. a=rtpmap:101 telephone-event/8000
  1293. a=fmtp:101 0-16
  1294. a=rtpmap:13 CN/8000
  1295. a=ptime:20
  1296. a=rtcp-mux
  1297. a=rtcp:27152 IN IP4 208.90.97.9
  1298.  
  1299. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1300. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1301. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1302. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1303. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1304. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1305. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  1306. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/101@thecarmel.uphone.ca PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1307. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_codec.c:111 sofia/internal/101@thecarmel.uphone.ca Original read codec set to PCMU:0
  1308. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  1309. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4747 sofia/internal/101@thecarmel.uphone.ca Set 2833 dtmf send payload to 101 recv payload to 101
  1310. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/101@thecarmel.uphone.ca] 208.90.97.6 port 22524 -> 208.90.97.9 port 27152 codec: 0 ms: 20
  1311. 2018-08-29 13:24:49.236044 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  1312. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 27152
  1313. 2018-08-29 13:24:49.236044 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 27152
  1314. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7109 sofia/internal/101@thecarmel.uphone.ca Set 2833 dtmf send payload to 101
  1315. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7116 sofia/internal/101@thecarmel.uphone.ca Set 2833 dtmf receive payload to 101
  1316. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7139 sofia/internal/101@thecarmel.uphone.ca Set rtp dtmf delay to 40
  1317. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7145 Set comfort noise payload to 13
  1318. 2018-08-29 13:24:49.236044 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/101@thecarmel.uphone.ca!
  1319. 2018-08-29 13:24:49.236044 [DEBUG] switch_channel.c:3473 (sofia/internal/101@thecarmel.uphone.ca) Callstate Change DOWN -> EARLY
  1320. 2018-08-29 13:24:49.236044 [INFO] switch_ivr_originate.c:1215 Sending early media
  1321. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1322. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1323. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1324. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1325. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1326. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1327. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1328. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1329. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1330. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1331. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1332. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1333. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  1334. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  1335. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  1336. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  1337. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
  1338. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  1339. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1340. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1341. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1342. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1343. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1344. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  1345. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:3049 Set Codec sofia/external/5195752944@208.89.129.46 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1346. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_codec.c:111 sofia/external/5195752944@208.89.129.46 Original read codec set to PCMU:0
  1347. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  1348. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:4747 sofia/external/5195752944@208.89.129.46 Set 2833 dtmf send payload to 101 recv payload to 101
  1349. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/external/5195752944@208.89.129.46] 208.90.97.6 port 22160 -> 208.89.128.69 port 59728 codec: 0 ms: 20
  1350. 2018-08-29 13:24:49.236044 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  1351. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 0
  1352. 2018-08-29 13:24:49.236044 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 59729
  1353. 2018-08-29 13:24:49.236044 [DEBUG] switch_rtp.c:2559 Setting RTCP remote addr to 208.89.128.69:59729 2
  1354. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7109 sofia/external/5195752944@208.89.129.46 Set 2833 dtmf send payload to 101
  1355. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7116 sofia/external/5195752944@208.89.129.46 Set 2833 dtmf receive payload to 101
  1356. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:7139 sofia/external/5195752944@208.89.129.46 Set rtp dtmf delay to 40
  1357. 2018-08-29 13:24:49.236044 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/5195752944@208.89.129.46!
  1358. 2018-08-29 13:24:49.236044 [DEBUG] switch_channel.c:3473 (sofia/external/5195752944@208.89.129.46) Callstate Change RINGING -> EARLY
  1359. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/external/5195752944@208.89.129.46.
  1360. 2018-08-29 13:24:49.236044 [DEBUG] mod_sofia.c:2363 Ring SDP:
  1361. v=0
  1362. o=FreeSWITCH 1535541329 1535541330 IN IP4 208.90.97.6
  1363. s=FreeSWITCH
  1364. c=IN IP4 208.90.97.6
  1365. t=0 0
  1366. m=audio 22160 RTP/AVP 0 101
  1367. a=rtpmap:0 PCMU/8000
  1368. a=rtpmap:101 telephone-event/8000
  1369. a=fmtp:101 0-16
  1370. a=ptime:20
  1371. a=sendrecv
  1372. a=rtcp:22161 IN IP4 208.90.97.6
  1373.  
  1374. 2018-08-29 13:24:49.236044 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  1375. 2018-08-29 13:24:49.236044 [DEBUG] switch_core_codec.c:223 sofia/external/5195752944@208.89.129.46 Push codec L16:100
  1376. 2018-08-29 13:24:49.236044 [DEBUG] switch_ivr_originate.c:1305 Play Ringback File [tone_stream://%(2000, 4000, 440.0, 480.0);loops=-1]
  1377. 2018-08-29 13:24:49.255938 [DEBUG] sofia.c:7048 Channel sofia/external/5195752944@208.89.129.46 entering state [early][183]
  1378. 2018-08-29 13:24:49.275954 [INFO] sofia.c:1279 sofia/internal/100@thecarmel.uphone.ca Update Callee ID to "100" <sip:100@thecarmel.uphone.ca>
  1379. 2018-08-29 13:24:49.275954 [DEBUG] sofia.c:7048 Channel sofia/internal/100@thecarmel.uphone.ca entering state [proceeding][183]
  1380. 2018-08-29 13:24:49.275954 [DEBUG] sofia.c:7058 Remote SDP:
  1381. v=0
  1382. o=FreeSWITCH 1535534653 1535534654 IN IP4 208.90.97.9
  1383. s=FreeSWITCH
  1384. c=IN IP4 208.90.97.9
  1385. t=0 0
  1386. m=audio 28836 RTP/AVP 0 101 13
  1387. a=rtpmap:0 PCMU/8000
  1388. a=rtpmap:101 telephone-event/8000
  1389. a=fmtp:101 0-16
  1390. a=rtpmap:13 CN/8000
  1391. a=ptime:20
  1392. a=rtcp-mux
  1393. a=rtcp:28836 IN IP4 208.90.97.9
  1394.  
  1395. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1396. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1397. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1398. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1399. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1400. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1401. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  1402. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/100@thecarmel.uphone.ca PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1403. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_codec.c:111 sofia/internal/100@thecarmel.uphone.ca Original read codec set to PCMU:0
  1404. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  1405. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:4747 sofia/internal/100@thecarmel.uphone.ca Set 2833 dtmf send payload to 101 recv payload to 101
  1406. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/100@thecarmel.uphone.ca] 208.90.97.6 port 30044 -> 208.90.97.9 port 28836 codec: 0 ms: 20
  1407. 2018-08-29 13:24:49.275954 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  1408. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 28836
  1409. 2018-08-29 13:24:49.275954 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 28836
  1410. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:7109 sofia/internal/100@thecarmel.uphone.ca Set 2833 dtmf send payload to 101
  1411. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:7116 sofia/internal/100@thecarmel.uphone.ca Set 2833 dtmf receive payload to 101
  1412. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:7139 sofia/internal/100@thecarmel.uphone.ca Set rtp dtmf delay to 40
  1413. 2018-08-29 13:24:49.275954 [DEBUG] switch_core_media.c:7145 Set comfort noise payload to 13
  1414. 2018-08-29 13:24:49.275954 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/100@thecarmel.uphone.ca!
  1415. 2018-08-29 13:24:49.275954 [DEBUG] switch_channel.c:3473 (sofia/internal/100@thecarmel.uphone.ca) Callstate Change DOWN -> EARLY
  1416. 2018-08-29 13:24:49.295929 [DEBUG] switch_rtp.c:1884 rtcp_stats_init: audio ssrc[158144313] base_seq[17813]
  1417. 2018-08-29 13:24:49.295929 [DEBUG] switch_rtp.c:7228 Correct audio ip/port confirmed.
  1418. 2018-08-29 13:24:49.335934 [INFO] sofia.c:1279 sofia/internal/105@thecarmel.uphone.ca Update Callee ID to "105" <sip:105@thecarmel.uphone.ca>
  1419. 2018-08-29 13:24:49.335934 [DEBUG] sofia.c:7048 Channel sofia/internal/105@thecarmel.uphone.ca entering state [proceeding][183]
  1420. 2018-08-29 13:24:49.335934 [DEBUG] sofia.c:7058 Remote SDP:
  1421. v=0
  1422. o=FreeSWITCH 1535532577 1535532578 IN IP4 208.90.97.9
  1423. s=FreeSWITCH
  1424. c=IN IP4 208.90.97.9
  1425. t=0 0
  1426. m=audio 30912 RTP/AVP 0 101 13
  1427. a=rtpmap:0 PCMU/8000
  1428. a=rtpmap:101 telephone-event/8000
  1429. a=fmtp:101 0-16
  1430. a=rtpmap:13 CN/8000
  1431. a=ptime:20
  1432. a=rtcp-mux
  1433. a=rtcp:30912 IN IP4 208.90.97.9
  1434.  
  1435. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1436. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1437. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1438. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1439. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1440. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1441. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  1442. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/105@thecarmel.uphone.ca PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1443. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_codec.c:111 sofia/internal/105@thecarmel.uphone.ca Original read codec set to PCMU:0
  1444. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  1445. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:4747 sofia/internal/105@thecarmel.uphone.ca Set 2833 dtmf send payload to 101 recv payload to 101
  1446. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/105@thecarmel.uphone.ca] 208.90.97.6 port 32604 -> 208.90.97.9 port 30912 codec: 0 ms: 20
  1447. 2018-08-29 13:24:49.335934 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  1448. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 30912
  1449. 2018-08-29 13:24:49.335934 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 30912
  1450. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:7109 sofia/internal/105@thecarmel.uphone.ca Set 2833 dtmf send payload to 101
  1451. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:7116 sofia/internal/105@thecarmel.uphone.ca Set 2833 dtmf receive payload to 101
  1452. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:7139 sofia/internal/105@thecarmel.uphone.ca Set rtp dtmf delay to 40
  1453. 2018-08-29 13:24:49.335934 [DEBUG] switch_core_media.c:7145 Set comfort noise payload to 13
  1454. 2018-08-29 13:24:49.335934 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/105@thecarmel.uphone.ca!
  1455. 2018-08-29 13:24:49.335934 [DEBUG] switch_channel.c:3473 (sofia/internal/105@thecarmel.uphone.ca) Callstate Change DOWN -> EARLY
  1456. 2018-08-29 13:24:49.355931 [INFO] sofia.c:1279 sofia/internal/109@thecarmel.uphone.ca Update Callee ID to "109" <sip:109@thecarmel.uphone.ca>
  1457. 2018-08-29 13:24:49.355931 [INFO] sofia.c:1279 sofia/internal/108@thecarmel.uphone.ca Update Callee ID to "108" <sip:108@thecarmel.uphone.ca>
  1458. 2018-08-29 13:24:49.355931 [DEBUG] sofia.c:7048 Channel sofia/internal/109@thecarmel.uphone.ca entering state [completing][200]
  1459. 2018-08-29 13:24:49.355931 [DEBUG] sofia.c:7058 Remote SDP:
  1460. v=0
  1461. o=FreeSWITCH 1535541505 1535541506 IN IP4 208.90.97.9
  1462. s=FreeSWITCH
  1463. c=IN IP4 208.90.97.9
  1464. t=0 0
  1465. m=audio 21984 RTP/AVP 0 101 13
  1466. a=rtpmap:0 PCMU/8000
  1467. a=rtpmap:101 telephone-event/8000
  1468. a=fmtp:101 0-16
  1469. a=rtpmap:13 CN/8000
  1470. a=ptime:20
  1471. a=rtcp-mux
  1472. a=rtcp:21984 IN IP4 208.90.97.9
  1473.  
  1474. 2018-08-29 13:24:49.355931 [DEBUG] sofia.c:7048 Channel sofia/internal/109@thecarmel.uphone.ca entering state [ready][200]
  1475. 2018-08-29 13:24:49.355931 [DEBUG] sofia.c:7048 Channel sofia/internal/108@thecarmel.uphone.ca entering state [proceeding][183]
  1476. 2018-08-29 13:24:49.355931 [DEBUG] sofia.c:7058 Remote SDP:
  1477. v=0
  1478. o=FreeSWITCH 1535533763 1535533764 IN IP4 208.90.97.9
  1479. s=FreeSWITCH
  1480. c=IN IP4 208.90.97.9
  1481. t=0 0
  1482. m=audio 29726 RTP/AVP 0 101 13
  1483. a=rtpmap:0 PCMU/8000
  1484. a=rtpmap:101 telephone-event/8000
  1485. a=fmtp:101 0-16
  1486. a=rtpmap:13 CN/8000
  1487. a=ptime:20
  1488. a=rtcp-mux
  1489. a=rtcp:29726 IN IP4 208.90.97.9
  1490.  
  1491. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1492. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1493. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1494. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1495. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1496. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1497. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  1498. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1499. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1500. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1501. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1502. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  1503. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/108@thecarmel.uphone.ca PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1504. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1505. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_codec.c:111 sofia/internal/108@thecarmel.uphone.ca Original read codec set to PCMU:0
  1506. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  1507. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/109@thecarmel.uphone.ca PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1508. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_codec.c:111 sofia/internal/109@thecarmel.uphone.ca Original read codec set to PCMU:0
  1509. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  1510. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4747 sofia/internal/109@thecarmel.uphone.ca Set 2833 dtmf send payload to 101 recv payload to 101
  1511. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/109@thecarmel.uphone.ca] 208.90.97.6 port 19878 -> 208.90.97.9 port 21984 codec: 0 ms: 20
  1512. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  1513. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:4747 sofia/internal/108@thecarmel.uphone.ca Set 2833 dtmf send payload to 101 recv payload to 101
  1514. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/108@thecarmel.uphone.ca] 208.90.97.6 port 24560 -> 208.90.97.9 port 29726 codec: 0 ms: 20
  1515. 2018-08-29 13:24:49.355931 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  1516. 2018-08-29 13:24:49.355931 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  1517. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 21984
  1518. 2018-08-29 13:24:49.355931 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 21984
  1519. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 29726
  1520. 2018-08-29 13:24:49.355931 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 29726
  1521. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7109 sofia/internal/109@thecarmel.uphone.ca Set 2833 dtmf send payload to 101
  1522. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7116 sofia/internal/109@thecarmel.uphone.ca Set 2833 dtmf receive payload to 101
  1523. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7139 sofia/internal/109@thecarmel.uphone.ca Set rtp dtmf delay to 40
  1524. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7145 Set comfort noise payload to 13
  1525. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7109 sofia/internal/108@thecarmel.uphone.ca Set 2833 dtmf send payload to 101
  1526. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7116 sofia/internal/108@thecarmel.uphone.ca Set 2833 dtmf receive payload to 101
  1527. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7139 sofia/internal/108@thecarmel.uphone.ca Set rtp dtmf delay to 40
  1528. 2018-08-29 13:24:49.355931 [DEBUG] switch_core_media.c:7145 Set comfort noise payload to 13
  1529. 2018-08-29 13:24:49.355931 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/108@thecarmel.uphone.ca!
  1530. 2018-08-29 13:24:49.355931 [NOTICE] sofia.c:8167 Channel [sofia/internal/109@thecarmel.uphone.ca] has been answered
  1531. 2018-08-29 13:24:49.355931 [DEBUG] switch_channel.c:3473 (sofia/internal/108@thecarmel.uphone.ca) Callstate Change DOWN -> EARLY
  1532. 2018-08-29 13:24:49.355931 [DEBUG] switch_channel.c:3772 (sofia/internal/109@thecarmel.uphone.ca) Callstate Change DOWN -> ACTIVE
  1533. EXECUTE sofia/internal/109@thecarmel.uphone.ca lua(/usr/share/freeswitch/scripts/confirm.lua)
  1534. 2018-08-29 13:24:49.355931 [DEBUG] switch_cpp.cpp:1112 sofia/internal/109@thecarmel.uphone.ca destroy/unlink session from object
  1535. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_codec.c:248 sofia/external/5195752944@208.89.129.46 Restore previous codec PCMU:0.
  1536. 2018-08-29 13:24:49.375934 [NOTICE] switch_ivr_originate.c:3605 Hangup sofia/internal/100@thecarmel.uphone.ca [CS_CONSUME_MEDIA] [LOSE_RACE]
  1537. 2018-08-29 13:24:49.375934 [NOTICE] switch_ivr_originate.c:3605 Hangup sofia/internal/101@thecarmel.uphone.ca [CS_CONSUME_MEDIA] [LOSE_RACE]
  1538. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@thecarmel.uphone.ca) Running State Change CS_HANGUP (Cur 8 Tot 46974)
  1539. 2018-08-29 13:24:49.375934 [NOTICE] switch_ivr_originate.c:3605 Hangup sofia/internal/105@thecarmel.uphone.ca [CS_CONSUME_MEDIA] [LOSE_RACE]
  1540. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/100@thecarmel.uphone.ca) Callstate Change EARLY -> HANGUP
  1541. 2018-08-29 13:24:49.375934 [NOTICE] switch_ivr_originate.c:3605 Hangup sofia/internal/106@thecarmel.uphone.ca [CS_CONSUME_MEDIA] [LOSE_RACE]
  1542. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100@thecarmel.uphone.ca) State HANGUP
  1543. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:438 Channel sofia/internal/100@thecarmel.uphone.ca hanging up, cause: LOSE_RACE
  1544. 2018-08-29 13:24:49.375934 [NOTICE] switch_ivr_originate.c:3605 Hangup sofia/internal/107@thecarmel.uphone.ca [CS_CONSUME_MEDIA] [LOSE_RACE]
  1545. 2018-08-29 13:24:49.375934 [NOTICE] switch_ivr_originate.c:3605 Hangup sofia/internal/108@thecarmel.uphone.ca [CS_CONSUME_MEDIA] [LOSE_RACE]
  1546. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/100@thecarmel.uphone.ca
  1547. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:60 sofia/internal/100@thecarmel.uphone.ca Standard HANGUP, cause: LOSE_RACE
  1548. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100@thecarmel.uphone.ca) State HANGUP going to sleep
  1549. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/100@thecarmel.uphone.ca) State Change CS_HANGUP -> CS_REPORTING
  1550. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/external/5195752944@208.89.129.46.
  1551. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/5195752944@208.89.129.46:
  1552. v=0
  1553. o=FreeSWITCH 1535541329 1535541331 IN IP4 208.90.97.6
  1554. s=FreeSWITCH
  1555. c=IN IP4 208.90.97.6
  1556. t=0 0
  1557. m=audio 22160 RTP/AVP 0 101
  1558. a=rtpmap:0 PCMU/8000
  1559. a=rtpmap:101 telephone-event/8000
  1560. a=fmtp:101 0-16
  1561. a=ptime:20
  1562. a=sendrecv
  1563. a=rtcp:22161 IN IP4 208.90.97.6
  1564.  
  1565. 2018-08-29 13:24:49.375934 [NOTICE] switch_ivr_originate.c:3632 Channel [sofia/external/5195752944@208.89.129.46] has been answered
  1566. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@thecarmel.uphone.ca) Running State Change CS_REPORTING (Cur 8 Tot 46974)
  1567. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100@thecarmel.uphone.ca) State REPORTING
  1568. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:174 sofia/internal/100@thecarmel.uphone.ca Standard REPORTING, cause: LOSE_RACE
  1569. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100@thecarmel.uphone.ca) State REPORTING going to sleep
  1570. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/100@thecarmel.uphone.ca) State Change CS_REPORTING -> CS_DESTROY
  1571. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_session.c:1664 Session 46968 (sofia/internal/100@thecarmel.uphone.ca) Locked, Waiting on external entities
  1572. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@thecarmel.uphone.ca) Running State Change CS_HANGUP (Cur 8 Tot 46974)
  1573. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@thecarmel.uphone.ca) Running State Change CS_HANGUP (Cur 8 Tot 46974)
  1574. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/101@thecarmel.uphone.ca) Callstate Change EARLY -> HANGUP
  1575. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/101@thecarmel.uphone.ca) State HANGUP
  1576. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:438 Channel sofia/internal/101@thecarmel.uphone.ca hanging up, cause: LOSE_RACE
  1577. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/106@thecarmel.uphone.ca) Callstate Change DOWN -> HANGUP
  1578. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/106@thecarmel.uphone.ca) State HANGUP
  1579. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:438 Channel sofia/internal/106@thecarmel.uphone.ca hanging up, cause: LOSE_RACE
  1580. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/105@thecarmel.uphone.ca) Running State Change CS_HANGUP (Cur 8 Tot 46974)
  1581. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/101@thecarmel.uphone.ca
  1582. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/105@thecarmel.uphone.ca) Callstate Change EARLY -> HANGUP
  1583. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:60 sofia/internal/101@thecarmel.uphone.ca Standard HANGUP, cause: LOSE_RACE
  1584. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/101@thecarmel.uphone.ca) State HANGUP going to sleep
  1585. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/105@thecarmel.uphone.ca) State HANGUP
  1586. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:438 Channel sofia/internal/105@thecarmel.uphone.ca hanging up, cause: LOSE_RACE
  1587. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/101@thecarmel.uphone.ca) State Change CS_HANGUP -> CS_REPORTING
  1588. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@thecarmel.uphone.ca) Running State Change CS_REPORTING (Cur 8 Tot 46974)
  1589. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/101@thecarmel.uphone.ca) State REPORTING
  1590. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:174 sofia/internal/101@thecarmel.uphone.ca Standard REPORTING, cause: LOSE_RACE
  1591. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/101@thecarmel.uphone.ca) State REPORTING going to sleep
  1592. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/101@thecarmel.uphone.ca) State Change CS_REPORTING -> CS_DESTROY
  1593. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_session.c:1664 Session 46969 (sofia/internal/101@thecarmel.uphone.ca) Locked, Waiting on external entities
  1594. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/107@thecarmel.uphone.ca) Running State Change CS_HANGUP (Cur 8 Tot 46974)
  1595. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/108@thecarmel.uphone.ca) Running State Change CS_HANGUP (Cur 8 Tot 46974)
  1596. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/105@thecarmel.uphone.ca
  1597. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:60 sofia/internal/105@thecarmel.uphone.ca Standard HANGUP, cause: LOSE_RACE
  1598. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/105@thecarmel.uphone.ca) State HANGUP going to sleep
  1599. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/105@thecarmel.uphone.ca) State Change CS_HANGUP -> CS_REPORTING
  1600. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/108@thecarmel.uphone.ca) Callstate Change EARLY -> HANGUP
  1601. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/105@thecarmel.uphone.ca) Running State Change CS_REPORTING (Cur 8 Tot 46974)
  1602. 2018-08-29 13:24:49.375934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/108@thecarmel.uphone.ca) State HANGUP
  1603. 2018-08-29 13:24:49.375934 [DEBUG] mod_sofia.c:438 Channel sofia/internal/108@thecarmel.uphone.ca hanging up, cause: LOSE_RACE
  1604. 2018-08-29 13:24:49.395934 [DEBUG] switch_channel.c:3772 (sofia/external/5195752944@208.89.129.46) Callstate Change EARLY -> ACTIVE
  1605. 2018-08-29 13:24:49.395934 [DEBUG] sofia.c:7048 Channel sofia/external/5195752944@208.89.129.46 entering state [completed][200]
  1606. 2018-08-29 13:24:49.395934 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/109@thecarmel.uphone.ca]
  1607. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/105@thecarmel.uphone.ca) State REPORTING
  1608. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:174 sofia/internal/105@thecarmel.uphone.ca Standard REPORTING, cause: LOSE_RACE
  1609. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/105@thecarmel.uphone.ca) State REPORTING going to sleep
  1610. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/105@thecarmel.uphone.ca) State Change CS_REPORTING -> CS_DESTROY
  1611. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_session.c:1664 Session 46970 (sofia/internal/105@thecarmel.uphone.ca) Locked, Waiting on external entities
  1612. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1682 Session 46970 (sofia/internal/105@thecarmel.uphone.ca) Ended
  1613. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/105@thecarmel.uphone.ca [CS_DESTROY]
  1614. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/105@thecarmel.uphone.ca) Running State Change CS_DESTROY (Cur 7 Tot 46974)
  1615. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/105@thecarmel.uphone.ca) State DESTROY
  1616. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:343 sofia/internal/105@thecarmel.uphone.ca SOFIA DESTROY
  1617. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:181 sofia/internal/105@thecarmel.uphone.ca Standard DESTROY
  1618. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/105@thecarmel.uphone.ca) State DESTROY going to sleep
  1619. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/107@thecarmel.uphone.ca) Callstate Change DOWN -> HANGUP
  1620. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/107@thecarmel.uphone.ca) State HANGUP
  1621. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/106@thecarmel.uphone.ca
  1622. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:438 Channel sofia/internal/107@thecarmel.uphone.ca hanging up, cause: LOSE_RACE
  1623. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:60 sofia/internal/106@thecarmel.uphone.ca Standard HANGUP, cause: LOSE_RACE
  1624. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/106@thecarmel.uphone.ca) State HANGUP going to sleep
  1625. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/106@thecarmel.uphone.ca) State Change CS_HANGUP -> CS_REPORTING
  1626. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@thecarmel.uphone.ca) Running State Change CS_REPORTING (Cur 7 Tot 46974)
  1627. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/106@thecarmel.uphone.ca) State REPORTING
  1628. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:174 sofia/internal/106@thecarmel.uphone.ca Standard REPORTING, cause: LOSE_RACE
  1629. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/106@thecarmel.uphone.ca) State REPORTING going to sleep
  1630. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/107@thecarmel.uphone.ca
  1631. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:60 sofia/internal/107@thecarmel.uphone.ca Standard HANGUP, cause: LOSE_RACE
  1632. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/107@thecarmel.uphone.ca) State HANGUP going to sleep
  1633. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/107@thecarmel.uphone.ca) State Change CS_HANGUP -> CS_REPORTING
  1634. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1682 Session 46969 (sofia/internal/101@thecarmel.uphone.ca) Ended
  1635. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/101@thecarmel.uphone.ca [CS_DESTROY]
  1636. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/106@thecarmel.uphone.ca) State Change CS_REPORTING -> CS_DESTROY
  1637. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_session.c:1664 Session 46971 (sofia/internal/106@thecarmel.uphone.ca) Locked, Waiting on external entities
  1638. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1682 Session 46971 (sofia/internal/106@thecarmel.uphone.ca) Ended
  1639. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/106@thecarmel.uphone.ca [CS_DESTROY]
  1640. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/101@thecarmel.uphone.ca) Running State Change CS_DESTROY (Cur 5 Tot 46974)
  1641. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/106@thecarmel.uphone.ca) Running State Change CS_DESTROY (Cur 5 Tot 46974)
  1642. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/101@thecarmel.uphone.ca) State DESTROY
  1643. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:343 sofia/internal/101@thecarmel.uphone.ca SOFIA DESTROY
  1644. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1682 Session 46968 (sofia/internal/100@thecarmel.uphone.ca) Ended
  1645. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/100@thecarmel.uphone.ca [CS_DESTROY]
  1646. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:181 sofia/internal/101@thecarmel.uphone.ca Standard DESTROY
  1647. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/101@thecarmel.uphone.ca) State DESTROY going to sleep
  1648. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/100@thecarmel.uphone.ca) Running State Change CS_DESTROY (Cur 4 Tot 46974)
  1649. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100@thecarmel.uphone.ca) State DESTROY
  1650. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:343 sofia/internal/100@thecarmel.uphone.ca SOFIA DESTROY
  1651. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:181 sofia/internal/100@thecarmel.uphone.ca Standard DESTROY
  1652. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100@thecarmel.uphone.ca) State DESTROY going to sleep
  1653. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/106@thecarmel.uphone.ca) State DESTROY
  1654. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:343 sofia/internal/106@thecarmel.uphone.ca SOFIA DESTROY
  1655. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:181 sofia/internal/106@thecarmel.uphone.ca Standard DESTROY
  1656. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/106@thecarmel.uphone.ca) State DESTROY going to sleep
  1657. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/107@thecarmel.uphone.ca) Running State Change CS_REPORTING (Cur 4 Tot 46974)
  1658. 2018-08-29 13:24:49.395934 [DEBUG] sofia.c:7048 Channel sofia/external/5195752944@208.89.129.46 entering state [ready][200]
  1659. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/108@thecarmel.uphone.ca
  1660. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:60 sofia/internal/108@thecarmel.uphone.ca Standard HANGUP, cause: LOSE_RACE
  1661. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/108@thecarmel.uphone.ca) State HANGUP going to sleep
  1662. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/108@thecarmel.uphone.ca) State Change CS_HANGUP -> CS_REPORTING
  1663. 2018-08-29 13:24:49.395934 [DEBUG] switch_ivr_bridge.c:1574 (sofia/internal/109@thecarmel.uphone.ca) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1664. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/108@thecarmel.uphone.ca) Running State Change CS_REPORTING (Cur 4 Tot 46974)
  1665. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/107@thecarmel.uphone.ca) State REPORTING
  1666. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:174 sofia/internal/107@thecarmel.uphone.ca Standard REPORTING, cause: LOSE_RACE
  1667. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/107@thecarmel.uphone.ca) State REPORTING going to sleep
  1668. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/108@thecarmel.uphone.ca) State REPORTING
  1669. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/107@thecarmel.uphone.ca) State Change CS_REPORTING -> CS_DESTROY
  1670. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:174 sofia/internal/108@thecarmel.uphone.ca Standard REPORTING, cause: LOSE_RACE
  1671. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/108@thecarmel.uphone.ca) State REPORTING going to sleep
  1672. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_session.c:1664 Session 46972 (sofia/internal/107@thecarmel.uphone.ca) Locked, Waiting on external entities
  1673. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1682 Session 46972 (sofia/internal/107@thecarmel.uphone.ca) Ended
  1674. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/107@thecarmel.uphone.ca [CS_DESTROY]
  1675. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/107@thecarmel.uphone.ca) Running State Change CS_DESTROY (Cur 3 Tot 46974)
  1676. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/107@thecarmel.uphone.ca) State DESTROY
  1677. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:343 sofia/internal/107@thecarmel.uphone.ca SOFIA DESTROY
  1678. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:181 sofia/internal/107@thecarmel.uphone.ca Standard DESTROY
  1679. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/107@thecarmel.uphone.ca) State DESTROY going to sleep
  1680. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/108@thecarmel.uphone.ca) State Change CS_REPORTING -> CS_DESTROY
  1681. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_session.c:1664 Session 46973 (sofia/internal/108@thecarmel.uphone.ca) Locked, Waiting on external entities
  1682. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1682 Session 46973 (sofia/internal/108@thecarmel.uphone.ca) Ended
  1683. 2018-08-29 13:24:49.395934 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/108@thecarmel.uphone.ca [CS_DESTROY]
  1684. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/108@thecarmel.uphone.ca) Running State Change CS_DESTROY (Cur 2 Tot 46974)
  1685. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/108@thecarmel.uphone.ca) State DESTROY
  1686. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:343 sofia/internal/108@thecarmel.uphone.ca SOFIA DESTROY
  1687. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:181 sofia/internal/108@thecarmel.uphone.ca Standard DESTROY
  1688. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/108@thecarmel.uphone.ca) State DESTROY going to sleep
  1689. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/109@thecarmel.uphone.ca) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 46974)
  1690. 2018-08-29 13:24:49.395934 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/109@thecarmel.uphone.ca) State EXCHANGE_MEDIA
  1691. 2018-08-29 13:24:49.395934 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  1692. 2018-08-29 13:24:49.435932 [DEBUG] switch_rtp.c:7228 Correct audio ip/port confirmed.
  1693. 2018-08-29 13:24:50.435929 [DEBUG] switch_rtp.c:7228 Correct audio ip/port confirmed.
  1694. 2018-08-29 13:24:50.455928 [DEBUG] switch_rtp.c:1884 rtcp_stats_init: audio ssrc[1652856672] base_seq[33176]
  1695. 2018-08-29 13:24:55.515939 [NOTICE] sofia.c:1012 Hangup sofia/internal/109@thecarmel.uphone.ca [CS_EXCHANGE_MEDIA] [NO_ANSWER]
  1696. 2018-08-29 13:24:55.515939 [DEBUG] switch_ivr_bridge.c:760 BRIDGE THREAD DONE [sofia/internal/109@thecarmel.uphone.ca]
  1697. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/109@thecarmel.uphone.ca) State EXCHANGE_MEDIA going to sleep
  1698. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/109@thecarmel.uphone.ca) Running State Change CS_HANGUP (Cur 2 Tot 46974)
  1699. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/109@thecarmel.uphone.ca) Callstate Change ACTIVE -> HANGUP
  1700. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/109@thecarmel.uphone.ca) State HANGUP
  1701. 2018-08-29 13:24:55.515939 [DEBUG] mod_sofia.c:438 Channel sofia/internal/109@thecarmel.uphone.ca hanging up, cause: NO_ANSWER
  1702. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:60 sofia/internal/109@thecarmel.uphone.ca Standard HANGUP, cause: NO_ANSWER
  1703. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/109@thecarmel.uphone.ca) State HANGUP going to sleep
  1704. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/109@thecarmel.uphone.ca) State Change CS_HANGUP -> CS_REPORTING
  1705. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/109@thecarmel.uphone.ca) Running State Change CS_REPORTING (Cur 2 Tot 46974)
  1706. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/109@thecarmel.uphone.ca) State REPORTING
  1707. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:174 sofia/internal/109@thecarmel.uphone.ca Standard REPORTING, cause: NO_ANSWER
  1708. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/109@thecarmel.uphone.ca) State REPORTING going to sleep
  1709. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/109@thecarmel.uphone.ca) State Change CS_REPORTING -> CS_DESTROY
  1710. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_session.c:1664 Session 46974 (sofia/internal/109@thecarmel.uphone.ca) Locked, Waiting on external entities
  1711. 2018-08-29 13:24:55.515939 [DEBUG] switch_ivr_bridge.c:681 sofia/internal/109@thecarmel.uphone.ca ending bridge by request from write function
  1712. 2018-08-29 13:24:55.515939 [DEBUG] switch_ivr_bridge.c:760 BRIDGE THREAD DONE [sofia/external/5195752944@208.89.129.46]
  1713. 2018-08-29 13:24:55.515939 [NOTICE] switch_ivr_bridge.c:1724 Hangup sofia/external/5195752944@208.89.129.46 [CS_EXECUTE] [NO_ANSWER]
  1714. 2018-08-29 13:24:55.515939 [NOTICE] switch_core_session.c:1682 Session 46974 (sofia/internal/109@thecarmel.uphone.ca) Ended
  1715. 2018-08-29 13:24:55.515939 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/109@thecarmel.uphone.ca [CS_DESTROY]
  1716. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/109@thecarmel.uphone.ca) Running State Change CS_DESTROY (Cur 1 Tot 46974)
  1717. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/109@thecarmel.uphone.ca) State DESTROY
  1718. 2018-08-29 13:24:55.515939 [DEBUG] mod_sofia.c:343 sofia/internal/109@thecarmel.uphone.ca SOFIA DESTROY
  1719. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:181 sofia/internal/109@thecarmel.uphone.ca Standard DESTROY
  1720. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/109@thecarmel.uphone.ca) State DESTROY going to sleep
  1721. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_session.c:2814 sofia/external/5195752944@208.89.129.46 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1722. 2018-08-29 13:24:55.515939 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f67a409d500 released.
  1723. 2018-08-29 13:24:55.515939 [DEBUG] switch_cpp.cpp:1112 sofia/external/5195752944@208.89.129.46 destroy/unlink session from object
  1724. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_session.c:2814 sofia/external/5195752944@208.89.129.46 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1725. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5195752944@208.89.129.46) State EXECUTE going to sleep
  1726. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_HANGUP (Cur 1 Tot 46974)
  1727. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:850 (sofia/external/5195752944@208.89.129.46) Callstate Change ACTIVE -> HANGUP
  1728. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5195752944@208.89.129.46) State HANGUP
  1729. 2018-08-29 13:24:55.515939 [DEBUG] mod_sofia.c:432 sofia/external/5195752944@208.89.129.46 Overriding SIP cause 480 with 200 from the other leg
  1730. 2018-08-29 13:24:55.515939 [DEBUG] mod_sofia.c:438 Channel sofia/external/5195752944@208.89.129.46 hanging up, cause: NO_ANSWER
  1731. 2018-08-29 13:24:55.515939 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/5195752944@208.89.129.46
  1732. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:60 sofia/external/5195752944@208.89.129.46 Standard HANGUP, cause: NO_ANSWER
  1733. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5195752944@208.89.129.46) State HANGUP going to sleep
  1734. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:619 (sofia/external/5195752944@208.89.129.46) State Change CS_HANGUP -> CS_REPORTING
  1735. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5195752944@208.89.129.46) Running State Change CS_REPORTING (Cur 1 Tot 46974)
  1736. 2018-08-29 13:24:55.515939 [DEBUG] switch_core_state_machine.c:938 (sofia/external/5195752944@208.89.129.46) State REPORTING
  1737. freeswitch@voip-ss-001.uphone.ca> /exit
  1738.