FreeSWITCH Pastebin

Outbound routing internally

From FusionPBX, 7 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/106cb45b
Embed
Download Paste or View Raw
  1. +OK log level  [7]
  2. 2017-05-11 15:29:12.588128 [NOTICE] switch_channel.c:1104 New Channel sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [0cf6c61f-fb63-4f24-970e-085fa3030556]
  3. 2017-05-11 15:29:12.588128 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_NEW (Cur 1 Tot 9)
  4. 2017-05-11 15:29:12.588128 [DEBUG] sofia.c:10028 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 receiving invite from 192.168.6.65:5060 version: 1.9.0 -385-13d5dff 64bit
  5. 2017-05-11 15:29:12.588128 [DEBUG] sofia.c:10144 IP 192.168.6.65 Approved by acl "domains[]". Access Granted.
  6. 2017-05-11 15:29:12.588128 [DEBUG] sofia.c:7247 Channel sofia/LocalISPInt/111@pbx.ourdomain.com:5060 entering state [received][100]
  7. 2017-05-11 15:29:12.608440 [DEBUG] sofia.c:7257 Remote SDP:
  8. v=0
  9. o=- 20037 20037 IN IP4 192.168.6.65
  10. s=SDP data
  11. c=IN IP4 192.168.6.65
  12. t=0 0
  13. m=audio 11860 RTP/AVP 9 0 8 18 101
  14. a=rtpmap:9 G722/8000
  15. a=rtpmap:0 PCMU/8000
  16. a=rtpmap:8 PCMA/8000
  17. a=rtpmap:18 G729/8000
  18. a=fmtp:18 annexb=no
  19. a=rtpmap:101 telephone-event/8000
  20. a=fmtp:101 0-15
  21. a=ptime:20
  22.  
  23. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  24. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  25. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  26. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  27. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  28. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  29. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  30. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  31. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  32. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  33. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  34. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  35. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  36. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  37. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  38. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  39. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  40. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  41. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
  42. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5030 Set telephone-event payload to 101@8000
  43. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:3425 Set Codec sofia/LocalISPInt/111@pbx.ourdomain.com:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
  44. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_codec.c:111 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Original read codec set to G722:9
  45. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5373 Set telephone-event payload to 101@8000
  46. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_media.c:5431 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  47. 2017-05-11 15:29:12.608440 [DEBUG] sofia.c:7670 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State Change CS_NEW -> CS_INIT
  48. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:603 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State NEW
  49. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_INIT (Cur 1 Tot 9)
  50. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:627 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State INIT
  51. 2017-05-11 15:29:12.608440 [DEBUG] mod_sofia.c:93 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 SOFIA INIT
  52. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:40 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard INIT
  53. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:48 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State Change CS_INIT -> CS_ROUTING
  54. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:627 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State INIT going to sleep
  55. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_ROUTING (Cur 1 Tot 9)
  56. 2017-05-11 15:29:12.608440 [DEBUG] switch_channel.c:2249 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Callstate Change DOWN -> RINGING
  57. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:643 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State ROUTING
  58. 2017-05-11 15:29:12.608440 [DEBUG] mod_sofia.c:154 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 SOFIA ROUTING
  59. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:236 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard ROUTING
  60. 2017-05-11 15:29:12.608440 [INFO] mod_dialplan_xml.c:637 Processing Mike Thomas <111>->8018300186 in context public
  61. 2017-05-11 15:29:12.608440 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7fd2cc01d060 Connected.
  62. 2017-05-11 15:29:12.608440 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7fd2cc01d060 released.
  63. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->Single_Day_Alteration] continue=true
  64. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [Single_Day_Alteration] context(public) =~ /public/ break=on-false
  65. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Date/TimeMatch (FAIL) [Single_Day_Alteration] break=on-false
  66. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->After_Hours_Saturday] continue=true
  67. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [After_Hours_Saturday] context(public) =~ /public/ break=on-false
  68. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Date/TimeMatch (FAIL) [After_Hours_Saturday] break=on-false
  69. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->After_Hours_Sunday] continue=true
  70. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [After_Hours_Sunday] context(public) =~ /public/ break=on-false
  71. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Date/TimeMatch (FAIL) [After_Hours_Sunday] break=on-false
  72. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->After_Hours_Evenings] continue=true
  73. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [After_Hours_Evenings] context(public) =~ /public/ break=on-false
  74. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Date/TimeMatch (FAIL) [After_Hours_Evenings] break=on-false
  75. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->After_Hours_Mornings] continue=true
  76. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [After_Hours_Mornings] context(public) =~ /public/ break=on-false
  77. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Date/TimeMatch (FAIL) [After_Hours_Mornings] break=on-false
  78. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->Normal_Open_Hours] continue=true
  79. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [Normal_Open_Hours] context(public) =~ /public/ break=on-false
  80. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [Normal_Open_Hours] destination_number(8018300186) =~ /^.*$/ break=on-false
  81. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(call_direction=inbound) INLINE
  82. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(call_direction=inbound)
  83. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [call_direction]=[inbound]
  84. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(domain_uuid=97eb5a16-0edb-462a-bd70-5503133a6e94) INLINE
  85. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(domain_uuid=97eb5a16-0edb-462a-bd70-5503133a6e94)
  86. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [domain_uuid]=[97eb5a16-0edb-462a-bd70-5503133a6e94]
  87. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(domain_name=pbx.ourdomain.com) INLINE
  88. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(domain_name=pbx.ourdomain.com)
  89. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [domain_name]=[pbx.ourdomain.com]
  90. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action log(SYSADMIN: Now in Normal_Open_Hours dialplan. Prepping to send to 5001 XML.)
  91. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action transfer(5001 XML pbx.ourdomain.com)
  92. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->After_Hours] continue=true
  93. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [After_Hours] context(public) =~ /public/ break=on-false
  94. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(call_direction=inbound) INLINE
  95. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(call_direction=inbound)
  96. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [call_direction]=[inbound]
  97. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(domain_uuid=97eb5a16-0edb-462a-bd70-5503133a6e94) INLINE
  98. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(domain_uuid=97eb5a16-0edb-462a-bd70-5503133a6e94)
  99. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [domain_uuid]=[97eb5a16-0edb-462a-bd70-5503133a6e94]
  100. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(domain_name=pbx.ourdomain.com) INLINE
  101. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(domain_name=pbx.ourdomain.com)
  102. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [domain_name]=[pbx.ourdomain.com]
  103. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action answer(answer)
  104. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action transfer(5003 XML)
  105. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [public->catch_all_numbers] continue=false
  106. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [catch_all_numbers] context(public) =~ /public/ break=on-false
  107. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [catch_all_numbers] destination_number(8018300186) =~ /^.*$/ break=on-false
  108. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(call_direction=inbound) INLINE
  109. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(call_direction=inbound)
  110. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [call_direction]=[inbound]
  111. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(domain_uuid=97eb5a16-0edb-462a-bd70-5503133a6e94) INLINE
  112. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(domain_uuid=97eb5a16-0edb-462a-bd70-5503133a6e94)
  113. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [domain_uuid]=[97eb5a16-0edb-462a-bd70-5503133a6e94]
  114. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(domain_name=pbx.ourdomain.com) INLINE
  115. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(domain_name=pbx.ourdomain.com)
  116. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [domain_name]=[pbx.ourdomain.com]
  117. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action transfer(5501)
  118. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:286 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State Change CS_ROUTING -> CS_EXECUTE
  119. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:643 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State ROUTING going to sleep
  120. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_EXECUTE (Cur 1 Tot 9)
  121. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:650 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State EXECUTE
  122. 2017-05-11 15:29:12.608440 [DEBUG] mod_sofia.c:209 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 SOFIA EXECUTE
  123. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:328 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard EXECUTE
  124. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 log(SYSADMIN: Now in Normal_Open_Hours dialplan. Prepping to send to 5001 XML.)
  125. 2017-05-11 15:29:12.608440 [DEBUG] mod_dptools.c:1764 Now in Normal_Open_Hours dialplan. Prepping to send to 5001 XML.
  126. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 transfer(5001 XML pbx.ourdomain.com)
  127. 2017-05-11 15:29:12.608440 [DEBUG] switch_ivr.c:2194 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State Change CS_EXECUTE -> CS_ROUTING
  128. 2017-05-11 15:29:12.608440 [NOTICE] switch_ivr.c:2201 Transfer sofia/LocalISPInt/111@pbx.ourdomain.com:5060 to XML[5001@pbx.ourdomain.com]
  129. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:650 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State EXECUTE going to sleep
  130. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_ROUTING (Cur 1 Tot 9)
  131. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:643 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State ROUTING
  132. 2017-05-11 15:29:12.608440 [DEBUG] mod_sofia.c:145 Call appears to be already acknowledged
  133. 2017-05-11 15:29:12.608440 [DEBUG] mod_sofia.c:154 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 SOFIA ROUTING
  134. 2017-05-11 15:29:12.608440 [DEBUG] switch_core_state_machine.c:236 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard ROUTING
  135. 2017-05-11 15:29:12.608440 [INFO] mod_dialplan_xml.c:637 Processing Mike Thomas <111>->5001 in context pbx.ourdomain.com
  136. 2017-05-11 15:29:12.608440 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7fd2cc01d060 Connected.
  137. 2017-05-11 15:29:12.628337 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7fd2cc01d060 released.
  138. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->call-direction] continue=true
  139. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=on-false
  140. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action log(SYSADMIN: Processing Call-Direction Dialplan)
  141. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->variables] continue=true
  142. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [variables] () =~ // break=on-false
  143. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action export(origination_callee_id_name=${destination_number})
  144. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  145. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->user_record] continue=true
  146. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] () =~ // break=on-false
  147. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  148. 2017-05-11 15:29:12.628337 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7fd2cc01d060 Connected.
  149. 2017-05-11 15:29:12.628337 [ERR] mod_lua.cpp:280 No Result
  150. 2017-05-11 15:29:12.628337 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7fd2cc01d060 released.
  151. 2017-05-11 15:29:12.628337 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7fd2cc01d060 Connected.
  152. 2017-05-11 15:29:12.648123 [ERR] mod_lua.cpp:280 No Result
  153. 2017-05-11 15:29:12.648123 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7fd2cc01d060 released.
  154. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(user_record=)
  155. 2017-05-11 15:29:12.648123 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [user_record]=[UNDEF]
  156. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  157. 2017-05-11 15:29:12.648123 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7fd2cc01d060 Connected.
  158. 2017-05-11 15:29:12.648123 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7fd2cc01d060 released.
  159. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(from_user_exists=true)
  160. 2017-05-11 15:29:12.648123 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [from_user_exists]=[true]
  161. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_exists}() =~ /^true$/ break=never
  162. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  163. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_exists}() =~ /^true$/ break=never
  164. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  165. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  166. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_exists}() =~ /^true$/ break=never
  167. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  168. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  169. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_exists}() =~ /^true$/ break=never
  170. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  171. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  172. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  173. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
  174. 2017-05-11 15:29:12.648123 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7fd2cc01d060 Connected.
  175. 2017-05-11 15:29:12.648123 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7fd2cc01d060 released.
  176. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(from_user_record=all)
  177. 2017-05-11 15:29:12.648123 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [from_user_record]=[all]
  178. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  179. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${from_user_record}(all) =~ /^all$/ break=never
  180. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(record_session=true) INLINE
  181. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(record_session=true)
  182. 2017-05-11 15:29:12.648123 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [record_session]=[true]
  183. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  184. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  185. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^inbound$/ break=never
  186. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  187. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  188. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^outbound$/ break=never
  189. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  190. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  191. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^local$/ break=never
  192. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [user_record] ${record_session}(true) =~ /^true$/ break=on-false
  193. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action export(nolocal:api_on_answer=uuid_record ${uuid} start ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext})
  194. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->redial] continue=true
  195. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [redial] destination_number(5001) =~ /^(redial|\*870)$/ break=on-true
  196. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [redial] () =~ // break=never
  197. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  198. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->speed_dial] continue=false
  199. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [speed_dial] destination_number(5001) =~ /^\*0(.*)$/ break=on-false
  200. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->freetdm.10d] continue=false
  201. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [freetdm.10d] destination_number(5001) =~ /^\+?1?(\d{10})$/ break=on-false
  202. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->freetdm.911] continue=false
  203. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [freetdm.911] destination_number(5001) =~ /^(911)$/ break=on-false
  204. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->agent_status] continue=false
  205. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [agent_status] destination_number(5001) =~ /^\*22$/ break=on-false
  206. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->agent_status_id] continue=false
  207. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [agent_status_id] destination_number(5001) =~ /^\*23$/ break=on-false
  208. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->Canada] continue=false
  209. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Canada] ${caller_id_name}(Mike Thomas) =~ /^([^#]+#)(.*)$/ break=never
  210. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Canada] destination_number(5001) =~ /^5502$/ break=on-false
  211. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->Operator] continue=false
  212. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Operator] ${caller_id_name}(Mike Thomas) =~ /^([^#]+#)(.*)$/ break=never
  213. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Operator] destination_number(5001) =~ /^5503$/ break=on-false
  214. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->Payments] continue=false
  215. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Payments] ${caller_id_name}(Mike Thomas) =~ /^([^#]+#)(.*)$/ break=never
  216. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Payments] destination_number(5001) =~ /^5501$/ break=on-false
  217. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->SpanishSupport] continue=false
  218. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [SpanishSupport] ${caller_id_name}(Mike Thomas) =~ /^([^#]+#)(.*)$/ break=never
  219. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [SpanishSupport] destination_number(5001) =~ /^5506$/ break=on-false
  220. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->Support] continue=false
  221. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Support] ${caller_id_name}(Mike Thomas) =~ /^([^#]+#)(.*)$/ break=never
  222. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [Support] destination_number(5001) =~ /^5500$/ break=on-false
  223. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->WillCall] continue=false
  224. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [WillCall] ${caller_id_name}(Mike Thomas) =~ /^([^#]+#)(.*)$/ break=never
  225. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [WillCall] destination_number(5001) =~ /^5504$/ break=on-false
  226. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->group-intercept] continue=false
  227. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [group-intercept] destination_number(5001) =~ /^\*8$/ break=on-false
  228. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->page-extension] continue=false
  229. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [page-extension] destination_number(5001) =~ /^\*8(\d{2,7})$/ break=on-false
  230. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->eavesdrop] continue=false
  231. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [eavesdrop] destination_number(5001) =~ /^\*33(\d{2,7})$/ break=on-false
  232. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->call_privacy] continue=false
  233. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [call_privacy] destination_number(5001) =~ /^\*67(\d+)$/ break=on-false
  234. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->call_return] continue=false
  235. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [call_return] destination_number(5001) =~ /^\*69$/ break=on-false
  236. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->extension_queue] continue=false
  237. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [extension_queue] destination_number(5001) =~ /^\*800(.*)$/ break=on-false
  238. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->intercept-ext] continue=false
  239. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [intercept-ext] destination_number(5001) =~ /^\*\*(\d+)$/ break=on-false
  240. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->dx] continue=false
  241. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [dx] destination_number(5001) =~ /^dx$/ break=on-false
  242. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->extension-intercom] continue=false
  243. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [extension-intercom] destination_number(5001) =~ /^\*8(\d{2,7})$/ break=on-false
  244. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->att_xfer] continue=false
  245. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [att_xfer] destination_number(5001) =~ /^att_xfer$/ break=on-false
  246. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->extension-to-voicemail] continue=false
  247. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [extension-to-voicemail] ${user_exists}() =~ /^true$/ break=on-false
  248. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->send_to_voicemail] continue=false
  249. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [send_to_voicemail] destination_number(5001) =~ /^\*99(\d{2,10})$/ break=on-false
  250. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->vmain] continue=false
  251. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [vmain] destination_number(5001) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
  252. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->xfer_vm] continue=false
  253. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [xfer_vm] destination_number(5001) =~ /^xfer_vm$/ break=on-false
  254. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->After_Hours] continue=true
  255. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [After_Hours] destination_number(5001) =~ /^5003$/ break=on-false
  256. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->is_transfer] continue=false
  257. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [is_transfer] destination_number(5001) =~ /^is_transfer$/ break=on-false
  258. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->vmain_user] continue=false
  259. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [vmain_user] destination_number(5001) =~ /^\*97$/ break=on-false
  260. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->After_Hours] continue=
  261. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (FAIL) [After_Hours] destination_number(5001) =~ /^5003$/ break=on-false
  262. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 parsing [pbx.ourdomain.com->Open_Hours] continue=
  263. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Regex (PASS) [Open_Hours] destination_number(5001) =~ /^5001$/ break=on-false
  264. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action answer()
  265. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action sleep(1000)
  266. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(hangup_after_bridge=true)
  267. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(ringback=local_stream://default)
  268. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(transfer_ringback=local_stream://default)
  269. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action set(ivr_menu_uuid=6114252d-1ec0-496c-87de-27c65e30897f)
  270. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action ivr(6114252d-1ec0-496c-87de-27c65e30897f)
  271. Dialplan: sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Action transfer(5503 XML pbx.ourdomain.com)
  272. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_state_machine.c:286 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State Change CS_ROUTING -> CS_EXECUTE
  273. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_state_machine.c:643 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State ROUTING going to sleep
  274. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_EXECUTE (Cur 1 Tot 9)
  275. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_state_machine.c:650 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State EXECUTE
  276. 2017-05-11 15:29:12.648123 [DEBUG] mod_sofia.c:209 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 SOFIA EXECUTE
  277. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_state_machine.c:328 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard EXECUTE
  278. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 log(SYSADMIN: Processing Call-Direction Dialplan)
  279. 2017-05-11 15:29:12.648123 [DEBUG] mod_dptools.c:1764 Processing Call-Direction Dialplan
  280. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 export(origination_callee_id_name=5001)
  281. 2017-05-11 15:29:12.648123 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[5001]
  282. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(RFC2822_DATE=Thu, 11 May 2017 15:29:12 -0600)
  283. 2017-05-11 15:29:12.648123 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [RFC2822_DATE]=[Thu, 11 May 2017 15:29:12 -0600]
  284. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 export(nolocal:api_on_answer=uuid_record 0cf6c61f-fb63-4f24-970e-085fa3030556 start /var/lib/freeswitch/recordings/pbx.ourdomain.com/archive/2017/May/11/0cf6c61f-fb63-4f24-970e-085fa3030556.wav)
  285. 2017-05-11 15:29:12.648123 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [api_on_answer]=[uuid_record 0cf6c61f-fb63-4f24-970e-085fa3030556 start /var/lib/freeswitch/recordings/pbx.ourdomain.com/archive/2017/May/11/0cf6c61f-fb63-4f24-970e-085fa3030556.wav]
  286. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 hash(insert/pbx.ourdomain.com-last_dial/111/5001)
  287. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 answer()
  288. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_media.c:8148 AUDIO RTP [sofia/LocalISPInt/111@pbx.ourdomain.com:5060] 192.168.3.40 port 23306 -> 192.168.6.65 port 11860 codec: 9 ms: 20
  289. 2017-05-11 15:29:12.648123 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
  290. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_media.c:8451 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Set 2833 dtmf send payload to 101
  291. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_media.c:8458 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Set 2833 dtmf receive payload to 101
  292. 2017-05-11 15:29:12.648123 [DEBUG] switch_core_media.c:8481 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Set rtp dtmf delay to 40
  293. 2017-05-11 15:29:12.648123 [DEBUG] mod_sofia.c:881 Local SDP sofia/LocalISPInt/111@pbx.ourdomain.com:5060:
  294. v=0
  295. o=FreeSWITCH 1494514846 1494514847 IN IP4 63.78.119.44
  296. s=FreeSWITCH
  297. c=IN IP4 63.78.119.44
  298. t=0 0
  299. m=audio 23306 RTP/AVP 9 101
  300. a=rtpmap:9 G722/8000
  301. a=rtpmap:101 telephone-event/8000
  302. a=fmtp:101 0-16
  303. a=ptime:20
  304. a=sendrecv
  305.  
  306. 2017-05-11 15:29:12.648123 [NOTICE] mod_dptools.c:1352 Channel [sofia/LocalISPInt/111@pbx.ourdomain.com:5060] has been answered
  307. 2017-05-11 15:29:12.648123 [DEBUG] switch_channel.c:3780 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Callstate Change RINGING -> ACTIVE
  308. 2017-05-11 15:29:12.648123 [DEBUG] sofia.c:7247 Channel sofia/LocalISPInt/111@pbx.ourdomain.com:5060 entering state [completed][200]
  309. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 sleep(1000)
  310. 2017-05-11 15:29:12.688127 [DEBUG] sofia.c:7247 Channel sofia/LocalISPInt/111@pbx.ourdomain.com:5060 entering state [ready][200]
  311. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(hangup_after_bridge=true)
  312. 2017-05-11 15:29:13.648127 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [hangup_after_bridge]=[true]
  313. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(ringback=local_stream://default)
  314. 2017-05-11 15:29:13.648127 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [ringback]=[local_stream://default]
  315. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(transfer_ringback=local_stream://default)
  316. 2017-05-11 15:29:13.648127 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [transfer_ringback]=[local_stream://default]
  317. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 set(ivr_menu_uuid=6114252d-1ec0-496c-87de-27c65e30897f)
  318. 2017-05-11 15:29:13.648127 [DEBUG] mod_dptools.c:1570 SET sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [ivr_menu_uuid]=[6114252d-1ec0-496c-87de-27c65e30897f]
  319. EXECUTE sofia/LocalISPInt/111@pbx.ourdomain.com:5060 ivr(6114252d-1ec0-496c-87de-27c65e30897f)
  320. 2017-05-11 15:29:13.648127 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7fd2cc01d060 Connected.
  321. 2017-05-11 15:29:13.668125 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7fd2cc01d060 released.
  322. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exit'
  323. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-sub'
  324. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
  325. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
  326. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-back'
  327. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-top'
  328. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:880 building menu '6114252d-1ec0-496c-87de-27c65e30897f'
  329. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '0'
  330. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '1'
  331. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '2'
  332. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '3'
  333. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '4'
  334. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '6'
  335. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
  336. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
  337. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
  338. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_menu.c:483 Executing IVR menu 6114252d-1ec0-496c-87de-27c65e30897f
  339. 2017-05-11 15:29:13.668125 [DEBUG] switch_core_file.c:342 File /usr/share/freeswitch/sounds/en/us/callie/PHONE_TREE_During_Business_Hours_Main_Menu_-_With_Canada.wav sample rate 44100 doesn't match requested rate 16000
  340. 2017-05-11 15:29:13.668125 [WARNING] switch_core_file.c:360 File has 2 channels, muxing to 1 channel will occur.
  341. 2017-05-11 15:29:13.668125 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@16000hz 1 channels 20ms
  342. 2017-05-11 15:29:15.408127 [NOTICE] sofia.c:1079 Hangup sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  343. 2017-05-11 15:29:15.408127 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/PHONE_TREE_During_Business_Hours_Main_Menu_-_With_Canada.wav
  344. 2017-05-11 15:29:15.408127 [DEBUG] switch_ivr_menu.c:377 waiting for 5/5 digits t/o 2000
  345. 2017-05-11 15:29:15.408127 [DEBUG] switch_ivr_menu.c:424 digits ''
  346. 2017-05-11 15:29:15.408127 [DEBUG] switch_ivr_menu.c:661 IVR menu '6114252d-1ec0-496c-87de-27c65e30897f' no input detected
  347. 2017-05-11 15:29:15.408127 [DEBUG] switch_ivr_menu.c:673 exit-sound '(null)'
  348. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_session.c:2884 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
  349. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:650 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State EXECUTE going to sleep
  350. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_HANGUP (Cur 1 Tot 9)
  351. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:850 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Callstate Change ACTIVE -> HANGUP
  352. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:852 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State HANGUP
  353. 2017-05-11 15:29:15.408127 [DEBUG] mod_sofia.c:449 Channel sofia/LocalISPInt/111@pbx.ourdomain.com:5060 hanging up, cause: NORMAL_CLEARING
  354. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:60 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard HANGUP, cause: NORMAL_CLEARING
  355. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:852 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State HANGUP going to sleep
  356. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:619 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State Change CS_HANGUP -> CS_REPORTING
  357. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:584 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_REPORTING (Cur 1 Tot 9)
  358. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:938 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State REPORTING
  359. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:174 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard REPORTING, cause: NORMAL_CLEARING
  360. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:938 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State REPORTING going to sleep
  361. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:610 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State Change CS_REPORTING -> CS_DESTROY
  362. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_session.c:1712 Session 9 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Locked, Waiting on external entities
  363. 2017-05-11 15:29:15.408127 [NOTICE] switch_core_session.c:1730 Session 9 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Ended
  364. 2017-05-11 15:29:15.408127 [NOTICE] switch_core_session.c:1734 Close Channel sofia/LocalISPInt/111@pbx.ourdomain.com:5060 [CS_DESTROY]
  365. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:741 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) Running State Change CS_DESTROY (Cur 0 Tot 9)
  366. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:751 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State DESTROY
  367. 2017-05-11 15:29:15.408127 [DEBUG] mod_sofia.c:354 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 SOFIA DESTROY
  368. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:181 sofia/LocalISPInt/111@pbx.ourdomain.com:5060 Standard DESTROY
  369. 2017-05-11 15:29:15.408127 [DEBUG] switch_core_state_machine.c:751 (sofia/LocalISPInt/111@pbx.ourdomain.com:5060) State DESTROY going to sleep

Reply to "Outbound routing internally"

captcha