From Timothy Olawuyi, 7 Years ago, written in FreeSWITCH.
- view diff
Embed
  1.  
  2. freeswitch@internal>
  3. freeswitch@internal>
  4. freeswitch@internal> originate {origination_caller_id_name=55101,origination_caller_id_number=55101,igonre_early_media=true}sofia/external/sip:+2348124523790@10.200.161.132 5000
  5. +OK c3869b92-c6c4-11e6-add0-0dd5d13e0dc6
  6.  
  7. 2016-12-20 15:58:27.755418 [DEBUG] switch_ivr_originate.c:2100 Parsing global variables
  8. 2016-12-20 15:58:27.755418 [DEBUG] switch_event.c:1698 Parsing variable [origination_caller_id_name]=[55101]
  9. 2016-12-20 15:58:27.755418 [DEBUG] switch_event.c:1698 Parsing variable [origination_caller_id_number]=[55101]
  10. 2016-12-20 15:58:27.755418 [DEBUG] switch_event.c:1698 Parsing variable [igonre_early_media]=[true]
  11. 2016-12-20 15:58:27.755418 [NOTICE] switch_channel.c:1055 New Channel sofia/external/%2B2348124523790@10.200.161.132 [c3869b92-c6c4-11e6-add0-0dd5d13e0dc6]
  12. 2016-12-20 15:58:27.755418 [DEBUG] mod_sofia.c:4701 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_NEW -> CS_INIT
  13. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  14. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:472 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_INIT
  15. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:512 (sofia/external/%2B2348124523790@10.200.161.132) State INIT
  16. 2016-12-20 15:58:27.755418 [DEBUG] mod_sofia.c:87 sofia/external/%2B2348124523790@10.200.161.132 SOFIA INIT
  17. 2016-12-20 15:58:27.755418 [DEBUG] sofia_glue.c:1232 sofia/external/%2B2348124523790@10.200.161.132 sending invite version: 1.4.18  64bit
  18. Local SDP:
  19. v=0
  20. o=FreeSWITCH 1482220153 1482220154 IN IP4 10.227.49.19
  21. s=FreeSWITCH
  22. c=IN IP4 10.227.49.19
  23. t=0 0
  24. m=audio 25754 RTP/AVP 0 8 3 101 13
  25. a=rtpmap:0 PCMU/8000
  26. a=rtpmap:8 PCMA/8000
  27. a=rtpmap:3 GSM/8000
  28. a=rtpmap:101 telephone-event/8000
  29. a=fmtp:101 0-16
  30. a=ptime:20
  31. a=sendrecv
  32.  
  33. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:40 sofia/external/%2B2348124523790@10.200.161.132 Standard INIT
  34. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:48 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_INIT -> CS_ROUTING
  35. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  36. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:512 (sofia/external/%2B2348124523790@10.200.161.132) State INIT going to sleep
  37. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:472 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_ROUTING
  38. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:528 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING
  39. 2016-12-20 15:58:27.755418 [DEBUG] mod_sofia.c:123 sofia/external/%2B2348124523790@10.200.161.132 SOFIA ROUTING
  40. 2016-12-20 15:58:27.755418 [DEBUG] switch_ivr_originate.c:67 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  41. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  42. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:528 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING going to sleep
  43. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:472 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_CONSUME_MEDIA
  44. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:547 (sofia/external/%2B2348124523790@10.200.161.132) State CONSUME_MEDIA
  45. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_state_machine.c:547 (sofia/external/%2B2348124523790@10.200.161.132) State CONSUME_MEDIA going to sleep
  46. send 1050 bytes to udp/[10.200.161.132]:5060 at 15:58:27.766321:
  47.    ------------------------------------------------------------------------
  48.    INVITE sip:+2348124523790@10.200.161.132 SIP/2.0
  49.    Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bKXj2Nv4D43SS2e
  50.    Max-Forwards: 70
  51.    From: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  52.    To: <sip:+2348124523790@10.200.161.132>
  53.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  54. freeswitch@internal>    CSeq: 100786889 INVITE
  55.    Contact: <sip:mod_sofia@10.227.47.13:5080>
  56.    User-Agent: FreeSWITCH-mod_sofia/1.4.18~64bit
  57.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  58.    Supported: timer, path, replaces
  59.    Allow-Events: talk, hold, conference, refer
  60.    Content-Type: application/sdp
  61.    Content-Disposition: session
  62.    Content-Length: 270
  63.    X-FS-Support: update_display,send_info
  64.    Remote-Party-ID: "55101" <sip:55101@10.227.47.13>;party=calling;screen=yes;privacy=off
  65.  
  66.    v=0
  67.    o=FreeSWITCH 1482220153 1482220154 IN IP4 10.227.49.19
  68.    s=FreeSWITCH
  69.    c=IN IP4 10.227.49.19
  70.    t=0 0
  71.    m=audio 25754 RTP/AVP 0 8 3 101 13
  72.    a=rtpmap:0 PCMU/8000
  73.    a=rtpmap:8 PCMA/8000
  74.    a=rtpmap:3 GSM/8000
  75.    a=rtpmap:101 telephone-event/8000
  76.    a=fmtp:101 0-16
  77.    a=ptime:20
  78.    ------------------------------------------------------------------------
  79. 2016-12-20 15:58:27.755418 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  80. 2016-12-20 15:58:27.755418 [DEBUG] sofia.c:6623 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [calling][0]
  81. recv 307 bytes from udp/[10.200.161.132]:5060 at 15:58:27.791236:
  82.    ------------------------------------------------------------------------
  83.    SIP/2.0 100 Trying
  84.    From: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  85.    To: <sip:+2348124523790@10.200.161.132>
  86.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bKXj2Nv4D43SS2e;received=10.227.47.13
  87.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  88.    CSeq: 100786889 INVITE
  89.    Content-Length:    0
  90.  
  91.    ------------------------------------------------------------------------
  92. recv 706 bytes from udp/[10.200.161.132]:5060 at 15:58:30.353518:
  93.    ------------------------------------------------------------------------
  94.    SIP/2.0 183 Session Progress
  95.    From: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  96.    To: <sip:+2348124523790@10.200.161.132>;tag=4203370473
  97.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bKXj2Nv4D43SS2e;received=10.227.47.13
  98.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  99.    CSeq: 100786889 INVITE
  100.    Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,PRACK,UPDATE
  101.    Content-Type: application/sdp
  102.    Contact: <sip:10.200.161.132:5060;transport=UDP>
  103.    Content-Length:  242
  104.  
  105.    v=0
  106.    o=- 1398662 1398662 IN IP4 TSS.LGTSS.LGTSS01.NG
  107.    s=-
  108.    c=IN IP4 10.200.12.27
  109.    t=0 0
  110.    a=sendrecv
  111.    m=audio 13100 RTP/AVP 8 101
  112.    c=IN IP4 10.200.12.27
  113.    a=rtpmap:8 PCMA/8000
  114.    a=rtpmap:101 telephone-event/8000
  115.    a=fmtp:101 0-15
  116.    a=maxptime:40
  117.    ------------------------------------------------------------------------
  118. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  119. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  120. 2016-12-20 15:58:30.335408 [DEBUG] sofia.c:6623 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [proceeding][183]
  121. 2016-12-20 15:58:30.335408 [DEBUG] sofia.c:6633 Remote SDP:
  122. v=0
  123. o=- 1398662 1398662 IN IP4 TSS.LGTSS.LGTSS01.NG
  124. s=-
  125. c=IN IP4 10.200.12.27
  126. t=0 0
  127. a=sendrecv
  128. m=audio 13100 RTP/AVP 8 101
  129. c=IN IP4 10.200.12.27
  130. a=rtpmap:8 PCMA/8000
  131. a=rtpmap:101 telephone-event/8000
  132. a=fmtp:101 0-15
  133. a=maxptime:40
  134.  
  135. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:3632 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[PCMU:0:8000:20:64000:1]
  136. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:3632 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[PCMA:8:8000:20:64000:1]
  137. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:3687 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  138. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:3632 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[GSM:3:8000:20:13200:1]
  139. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:3548 Set telephone-event payload to 101
  140. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:2473 Set Codec sofia/external/%2B2348124523790@10.200.161.132 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  141. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_codec.c:111 sofia/external/%2B2348124523790@10.200.161.132 Original read codec set to PCMA:8
  142. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:3886 Set 2833 dtmf send payload to 101
  143. 2016-12-20 15:58:30.335408 [DEBUG] switch_core_media.c:5147 AUDIO RTP [sofia/external/%2B2348124523790@10.200.161.132] 10.227.49.19 port 25754 -> 10.200.12.27 port 13100 codec: 8 ms: 20
  144. 2016-12-20 15:58:30.335408 [DEBUG] switch_rtp.c:3562 Starting timer [soft] 160 bytes per 20ms
  145. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_media.c:5445 Set 2833 dtmf send payload to 101
  146. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_media.c:5451 Set 2833 dtmf receive payload to 101
  147. 2016-12-20 15:58:30.355402 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/%2B2348124523790@10.200.161.132!
  148. 2016-12-20 15:58:30.355402 [DEBUG] switch_channel.c:3399 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change DOWN -> EARLY
  149. 2016-12-20 15:58:30.355402 [DEBUG] switch_ivr_originate.c:3577 Originate Resulted in Success: [sofia/external/%2B2348124523790@10.200.161.132]
  150. 2016-12-20 15:58:30.355402 [DEBUG] switch_ivr.c:1854 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_CONSUME_MEDIA -> CS_ROUTING
  151. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  152. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_session.c:912 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  153. 2016-12-20 15:58:30.355402 [NOTICE] switch_ivr.c:1861 Transfer sofia/external/%2B2348124523790@10.200.161.132 to XML[5000@default]
  154. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:472 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_ROUTING
  155. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:528 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING
  156. 2016-12-20 15:58:30.355402 [DEBUG] mod_sofia.c:123 sofia/external/%2B2348124523790@10.200.161.132 SOFIA ROUTING
  157. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:166 sofia/external/%2B2348124523790@10.200.161.132 Standard ROUTING
  158. 2016-12-20 15:58:30.355402 [INFO] mod_dialplan_xml.c:635 Processing 55101 <55101>->5000 in context default
  159. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->unloop] continue=false
  160. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  161. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  162. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->tod_example] continue=true
  163. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Date/Time Match (PASS) [tod_example] break=on-false
  164. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Action set(open=true)
  165. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->holiday_example] continue=true
  166. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  167. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->global-intercept] continue=false
  168. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [global-intercept] destination_number(5000) =~ /^886$/ break=on-false
  169. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->group-intercept] continue=false
  170. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [group-intercept] destination_number(5000) =~ /^\*8$/ break=on-false
  171. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->intercept-ext] continue=false
  172. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [intercept-ext] destination_number(5000) =~ /^\*\*(\d+)$/ break=on-false
  173. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->redial] continue=false
  174. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [redial] destination_number(5000) =~ /^(redial|870)$/ break=on-false
  175. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->global] continue=true
  176. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  177. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [global] ${default_password}(65432) =~ /^1234$/ break=never
  178. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  179. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [global] ${endpoint_disposition}(EARLY MEDIA) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  180. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->snom-demo-2] continue=false
  181. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [snom-demo-2] destination_number(5000) =~ /^9001$/ break=on-false
  182. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->snom-demo-1] continue=false
  183. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [snom-demo-1] destination_number(5000) =~ /^9000$/ break=on-false
  184. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->eavesdrop] continue=false
  185. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [eavesdrop] destination_number(5000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  186. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->eavesdrop] continue=false
  187. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [eavesdrop] destination_number(5000) =~ /^779$/ break=on-false
  188. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->call_return] continue=false
  189. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [call_return] destination_number(5000) =~ /^\*69$|^869$|^lcr$/ break=on-false
  190. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->del-group] continue=false
  191. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [del-group] destination_number(5000) =~ /^80(\d{2})$/ break=on-false
  192. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->add-group] continue=false
  193. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [add-group] destination_number(5000) =~ /^81(\d{2})$/ break=on-false
  194. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->call-group-simo] continue=false
  195. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [call-group-simo] destination_number(5000) =~ /^82(\d{2})$/ break=on-false
  196. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->call-group-order] continue=false
  197. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [call-group-order] destination_number(5000) =~ /^83(\d{2})$/ break=on-false
  198. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->extension-intercom] continue=false
  199. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [extension-intercom] destination_number(5000) =~ /^8(10[01][0-9])$/ break=on-false
  200. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->Local_Extension] continue=false
  201. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [Local_Extension] destination_number(5000) =~ /^(10[01][0-9])$/ break=on-false
  202. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->Local_Extension_Skinny] continue=false
  203. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [Local_Extension_Skinny] destination_number(5000) =~ /^(11[01][0-9])$/ break=on-false
  204. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->group_dial_sales] continue=false
  205. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [group_dial_sales] destination_number(5000) =~ /^2000$/ break=on-false
  206. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->group_dial_support] continue=false
  207. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [group_dial_support] destination_number(5000) =~ /^2001$/ break=on-false
  208. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->group_dial_billing] continue=false
  209. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [group_dial_billing] destination_number(5000) =~ /^2002$/ break=on-false
  210. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->operator] continue=false
  211. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [operator] destination_number(5000) =~ /^(operator|0)$/ break=on-false
  212. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->vmain] continue=false
  213. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [vmain] destination_number(5000) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  214. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->sip_uri] continue=false
  215. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [sip_uri] destination_number(5000) =~ /^sip:(.*)$/ break=on-false
  216. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->nb_conferences] continue=false
  217. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [nb_conferences] destination_number(5000) =~ /^(30\d{2})$/ break=on-false
  218. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->wb_conferences] continue=false
  219. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [wb_conferences] destination_number(5000) =~ /^(31\d{2})$/ break=on-false
  220. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->uwb_conferences] continue=false
  221. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [uwb_conferences] destination_number(5000) =~ /^(32\d{2})$/ break=on-false
  222. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->cdquality_conferences] continue=false
  223. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [cdquality_conferences] destination_number(5000) =~ /^(33\d{2})$/ break=on-false
  224. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->freeswitch_public_conf_via_sip] continue=false
  225. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(5000) =~ /^9(888|8888|1616|3232)$/ break=on-false
  226. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->mad_boss_intercom] continue=false
  227. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [mad_boss_intercom] destination_number(5000) =~ /^0911$/ break=on-false
  228. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->mad_boss_intercom] continue=false
  229. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [mad_boss_intercom] destination_number(5000) =~ /^0912$/ break=on-false
  230. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->mad_boss] continue=false
  231. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (FAIL) [mad_boss] destination_number(5000) =~ /^0913$/ break=on-false
  232. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 parsing [default->ivr_demo] continue=false
  233. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Regex (PASS) [ivr_demo] destination_number(5000) =~ /^5000$/ break=on-false
  234. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Action answer()
  235. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Action sleep(2000)
  236. Dialplan: sofia/external/%2B2348124523790@10.200.161.132 Action ivr(demo_ivr)
  237. 2016-12-20 15:58:30.355402 [INFO] switch_channel.c:3062 sofia/external/%2B2348124523790@10.200.161.132 Flipping CID from "55101" <55101> to "Outbound Call" <+2348124523790>
  238. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:216 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_ROUTING -> CS_EXECUTE
  239. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  240. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:528 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING going to sleep
  241. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:472 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_EXECUTE
  242. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:535 (sofia/external/%2B2348124523790@10.200.161.132) State EXECUTE
  243. 2016-12-20 15:58:30.355402 [DEBUG] mod_sofia.c:178 sofia/external/%2B2348124523790@10.200.161.132 SOFIA EXECUTE
  244. 2016-12-20 15:58:30.355402 [DEBUG] switch_core_state_machine.c:258 sofia/external/%2B2348124523790@10.200.161.132 Standard EXECUTE
  245. EXECUTE sofia/external/%2B2348124523790@10.200.161.132 set(open=true)
  246. 2016-12-20 15:58:30.355402 [DEBUG] mod_dptools.c:1445 sofia/external/%2B2348124523790@10.200.161.132 SET [open]=[true]
  247. EXECUTE sofia/external/%2B2348124523790@10.200.161.132 answer()
  248. EXECUTE sofia/external/%2B2348124523790@10.200.161.132 sleep(2000)
  249. recv 373 bytes from udp/[10.200.161.132]:5060 at 15:58:31.448784:
  250.    ------------------------------------------------------------------------
  251.    SIP/2.0 180 Ringing
  252.    From: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  253.    To: <sip:+2348124523790@10.200.161.132>;tag=4203370473
  254.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bKXj2Nv4D43SS2e;received=10.227.47.13
  255.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  256.    CSeq: 100786889 INVITE
  257.    Contact: <sip:10.200.161.132:5060;transport=UDP>
  258.    Content-Length:    0
  259.  
  260.    ------------------------------------------------------------------------
  261. 2016-12-20 15:58:31.435334 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  262. 2016-12-20 15:58:31.435334 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  263. 2016-12-20 15:58:31.455399 [DEBUG] sofia.c:6623 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [proceeding][180]
  264. 2016-12-20 15:58:31.455399 [NOTICE] sofia.c:6725 Ring-Ready sofia/external/%2B2348124523790@10.200.161.132!
  265. 2016-12-20 15:58:31.455399 [DEBUG] switch_channel.c:3277 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change EARLY -> RINGING
  266. 2016-12-20 15:58:31.455399 [DEBUG] switch_rtp.c:5870 Correct ip/port confirmed.
  267. EXECUTE sofia/external/%2B2348124523790@10.200.161.132 ivr(demo_ivr)
  268. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-exit'
  269. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-sub'
  270. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
  271. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
  272. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-back'
  273. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-top'
  274. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:858 building menu 'demo_ivr'
  275. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '1'
  276. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '2'
  277. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '3'
  278. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '4'
  279. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '5'
  280. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:858 building menu 'demo_ivr_submenu'
  281. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-top' to '*'
  282. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-sub' to '6'
  283. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '/^(10[01][0-9])$/'
  284. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-top' to '9'
  285. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_menu.c:469 Executing IVR menu demo_ivr
  286. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  287. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-welcome_to_freeswitch.wav] (en:en)
  288. 2016-12-20 15:58:32.395356 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  289. 2016-12-20 15:58:34.995350 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-welcome_to_freeswitch.wav
  290. 2016-12-20 15:58:35.115350 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-this_ivr_will_let_you_test_features.wav] (en:en)
  291. 2016-12-20 15:58:35.135386 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  292. 2016-12-20 15:58:39.095349 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-this_ivr_will_let_you_test_features.wav
  293. 2016-12-20 15:58:39.235397 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-you_may_exit_by_hanging_up.wav] (en:en)
  294. 2016-12-20 15:58:39.255383 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  295. 2016-12-20 15:58:41.835349 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-you_may_exit_by_hanging_up.wav
  296. 2016-12-20 15:58:41.955347 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-enter_ext_pound.wav] (en:en)
  297. 2016-12-20 15:58:41.975350 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  298. recv 778 bytes from udp/[10.200.161.132]:5060 at 15:58:42.764695:
  299.    ------------------------------------------------------------------------
  300.    SIP/2.0 200 OK
  301.    From: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  302.    To: <sip:+2348124523790@10.200.161.132>;tag=4203370473
  303.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bKXj2Nv4D43SS2e;received=10.227.47.13
  304.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  305.    CSeq: 100786889 INVITE
  306.    Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,PRACK,UPDATE
  307.    P-Asserted-Identity: <sip:8124523790@LGTSS01SIP;user=phone>
  308.    Accept: application/sdp
  309.    Content-Type: application/sdp
  310.    Contact: <sip:10.200.161.132:5060;transport=UDP>
  311.    Content-Length:  242
  312.  
  313.    v=0
  314.    o=- 1398662 1398662 IN IP4 TSS.LGTSS.LGTSS01.NG
  315.    s=-
  316.    c=IN IP4 10.200.12.27
  317.    t=0 0
  318.    a=sendrecv
  319.    m=audio 13100 RTP/AVP 8 101
  320.    c=IN IP4 10.200.12.27
  321.    a=rtpmap:8 PCMA/8000
  322.    a=rtpmap:101 telephone-event/8000
  323.    a=fmtp:101 0-15
  324.    a=maxptime:40
  325.    ------------------------------------------------------------------------
  326. 2016-12-20 15:58:42.755348 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  327. 2016-12-20 15:58:42.755348 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  328. 2016-12-20 15:58:42.775350 [INFO] sofia.c:1192 sofia/external/%2B2348124523790@10.200.161.132 Update Caller ID to "Outbound Call" <8124523790>
  329. 2016-12-20 15:58:42.775350 [DEBUG] sofia.c:6623 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [completing][200]
  330. 2016-12-20 15:58:42.775350 [DEBUG] sofia.c:6630 Duplicate SDP
  331. v=0
  332. o=- 1398662 1398662 IN IP4 TSS.LGTSS.LGTSS01.NG
  333. s=-
  334. c=IN IP4 10.200.12.27
  335. t=0 0
  336. a=sendrecv
  337. m=audio 13100 RTP/AVP 8 101
  338. c=IN IP4 10.200.12.27
  339. a=rtpmap:8 PCMA/8000
  340. a=rtpmap:101 telephone-event/8000
  341. a=fmtp:101 0-15
  342. a=maxptime:40
  343.  
  344. send 386 bytes to udp/[10.200.161.132]:5060 at 15:58:42.776529:
  345.    ------------------------------------------------------------------------
  346.    ACK sip:10.200.161.132:5060;transport=UDP SIP/2.0
  347.    Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bKyUUeyZy702FNa
  348.    Max-Forwards: 70
  349.    From: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  350.    To: <sip:+2348124523790@10.200.161.132>;tag=4203370473
  351.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  352.    CSeq: 100786889 ACK
  353.    Contact: <sip:mod_sofia@10.227.47.13:5080>
  354.    Content-Length: 0
  355.  
  356.    ------------------------------------------------------------------------
  357. 2016-12-20 15:58:42.775350 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  358. 2016-12-20 15:58:42.775350 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  359. 2016-12-20 15:58:42.775350 [DEBUG] sofia.c:6623 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [ready][200]
  360. 2016-12-20 15:58:42.795392 [NOTICE] sofia.c:7425 Channel [sofia/external/%2B2348124523790@10.200.161.132] has been answered
  361. 2016-12-20 15:58:42.795392 [DEBUG] switch_channel.c:3689 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change RINGING -> ACTIVE
  362. 2016-12-20 15:58:44.635349 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-enter_ext_pound.wav
  363. 2016-12-20 15:58:44.775337 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[silence_stream://1500] (en:en)
  364. 2016-12-20 15:58:44.795387 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  365. 2016-12-20 15:58:46.275345 [DEBUG] switch_ivr_play_say.c:1747 done playing file silence_stream://1500
  366. 2016-12-20 15:58:46.415348 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_call_the_freeswitch_conference.wav] (en:en)
  367. 2016-12-20 15:58:46.435383 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  368. 2016-12-20 15:58:48.055349 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_call_the_freeswitch_conference.wav
  369. 2016-12-20 15:58:48.195351 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  370. 2016-12-20 15:58:48.195351 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  371. 2016-12-20 15:58:48.615351 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  372. 2016-12-20 15:58:48.735343 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  373. 2016-12-20 15:58:48.755393 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  374. 2016-12-20 15:58:49.135351 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  375. 2016-12-20 15:58:49.295348 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/1.wav] (en:en)
  376. 2016-12-20 15:58:49.295348 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  377. 2016-12-20 15:58:49.735349 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/1.wav
  378. 2016-12-20 15:58:49.855349 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_do_a_freeswitch_echo_test.wav] (en:en)
  379. 2016-12-20 15:58:49.855349 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  380. 2016-12-20 15:58:51.655349 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_do_a_freeswitch_echo_test.wav
  381. 2016-12-20 15:58:51.795350 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  382. 2016-12-20 15:58:51.795350 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  383. 2016-12-20 15:58:52.215330 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  384. 2016-12-20 15:58:52.315339 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  385. 2016-12-20 15:58:52.315339 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  386. 2016-12-20 15:58:52.715325 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  387. 2016-12-20 15:58:52.815329 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/2.wav] (en:en)
  388. 2016-12-20 15:58:52.815329 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  389. 2016-12-20 15:58:53.195335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/2.wav
  390. 2016-12-20 15:58:53.335404 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_listen_to_moh.wav] (en:en)
  391. 2016-12-20 15:58:53.355334 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  392. 2016-12-20 15:58:54.915401 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_listen_to_moh.wav
  393. 2016-12-20 15:58:55.075334 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  394. 2016-12-20 15:58:55.075334 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  395. 2016-12-20 15:58:55.495334 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  396. 2016-12-20 15:58:55.595336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  397. 2016-12-20 15:58:55.615336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  398. 2016-12-20 15:58:55.995401 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  399. 2016-12-20 15:58:56.115403 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/3.wav] (en:en)
  400. 2016-12-20 15:58:56.135421 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  401. 2016-12-20 15:58:56.195324 [DEBUG] switch_rtp.c:6092 RTP RECV DTMF 6:560
  402. 2016-12-20 15:58:56.215334 [DEBUG] switch_channel.c:488 RECV DTMF 6:560
  403. 2016-12-20 15:58:56.215334 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/3.wav
  404. 2016-12-20 15:58:56.315335 [DEBUG] switch_ivr_menu.c:363 waiting for 3/4 digits t/o 2000
  405. 2016-12-20 15:58:58.355336 [DEBUG] switch_ivr_menu.c:410 digits '6'
  406. 2016-12-20 15:58:58.355336 [DEBUG] switch_ivr_menu.c:561 IVR action on menu 'demo_ivr' matched '6' param 'demo_ivr_submenu'
  407. 2016-12-20 15:58:58.355336 [DEBUG] switch_ivr_menu.c:565 switch_ivr_menu_execute todo=[1]
  408. 2016-12-20 15:58:58.355336 [DEBUG] switch_ivr_menu.c:469 Executing IVR menu demo_ivr_submenu
  409. 2016-12-20 15:58:58.355336 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  410. 2016-12-20 15:58:58.355336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-welcome_to_freeswitch.wav] (en:en)
  411. 2016-12-20 15:58:58.355336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  412. 2016-12-20 15:59:00.955390 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-welcome_to_freeswitch.wav
  413. 2016-12-20 15:59:01.095400 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-sample_submenu.wav] (en:en)
  414. 2016-12-20 15:59:01.115397 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  415. 2016-12-20 15:59:07.355401 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-sample_submenu.wav
  416. 2016-12-20 15:59:07.515335 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_return_to_previous_menu.wav] (en:en)
  417. 2016-12-20 15:59:07.515335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  418. 2016-12-20 15:59:09.075408 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_return_to_previous_menu.wav
  419. 2016-12-20 15:59:09.215402 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  420. 2016-12-20 15:59:09.235408 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  421. 2016-12-20 15:59:09.635396 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  422. 2016-12-20 15:59:09.775397 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  423. 2016-12-20 15:59:09.795406 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  424. 2016-12-20 15:59:10.175411 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  425. 2016-12-20 15:59:10.315396 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/star.wav] (en:en)
  426. 2016-12-20 15:59:10.335411 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  427. 2016-12-20 15:59:10.815396 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/star.wav
  428. 2016-12-20 15:59:10.955397 [DEBUG] switch_ivr_menu.c:363 waiting for 1/1 digits t/o 7500
  429. 2016-12-20 15:59:14.315400 [DEBUG] switch_rtp.c:6092 RTP RECV DTMF *:560
  430. 2016-12-20 15:59:14.335401 [DEBUG] switch_channel.c:488 RECV DTMF *:560
  431. 2016-12-20 15:59:14.335401 [DEBUG] switch_ivr_menu.c:410 digits '*'
  432. 2016-12-20 15:59:14.335401 [DEBUG] switch_ivr_menu.c:561 IVR action on menu 'demo_ivr_submenu' matched '*' param ''
  433. 2016-12-20 15:59:14.335401 [DEBUG] switch_ivr_menu.c:565 switch_ivr_menu_execute todo=[5]
  434. 2016-12-20 15:59:14.335401 [DEBUG] switch_ivr_menu.c:543 action regex [6] [/^(10[01][0-9])$/] [0]
  435. 2016-12-20 15:59:14.335401 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  436. 2016-12-20 15:59:14.335401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-welcome_to_freeswitch.wav] (en:en)
  437. 2016-12-20 15:59:14.335401 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  438. 2016-12-20 15:59:16.935402 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-welcome_to_freeswitch.wav
  439. 2016-12-20 15:59:17.075402 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-this_ivr_will_let_you_test_features.wav] (en:en)
  440. 2016-12-20 15:59:17.095438 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  441. 2016-12-20 15:59:21.055399 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-this_ivr_will_let_you_test_features.wav
  442. 2016-12-20 15:59:21.175403 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-you_may_exit_by_hanging_up.wav] (en:en)
  443. 2016-12-20 15:59:21.195441 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  444. 2016-12-20 15:59:23.775401 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-you_may_exit_by_hanging_up.wav
  445. 2016-12-20 15:59:23.895402 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-enter_ext_pound.wav] (en:en)
  446. 2016-12-20 15:59:23.915443 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  447. 2016-12-20 15:59:26.595333 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-enter_ext_pound.wav
  448. 2016-12-20 15:59:26.695401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[silence_stream://1500] (en:en)
  449. 2016-12-20 15:59:26.715439 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  450. 2016-12-20 15:59:28.195399 [DEBUG] switch_ivr_play_say.c:1747 done playing file silence_stream://1500
  451. 2016-12-20 15:59:28.335336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_call_the_freeswitch_conference.wav] (en:en)
  452. 2016-12-20 15:59:28.335336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  453. 2016-12-20 15:59:29.955408 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_call_the_freeswitch_conference.wav
  454. 2016-12-20 15:59:30.095401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  455. 2016-12-20 15:59:30.115440 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  456. 2016-12-20 15:59:30.515400 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  457. 2016-12-20 15:59:30.655405 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  458. 2016-12-20 15:59:30.675440 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  459. 2016-12-20 15:59:31.055416 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  460. 2016-12-20 15:59:31.195335 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/1.wav] (en:en)
  461. 2016-12-20 15:59:31.195335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  462. 2016-12-20 15:59:31.635402 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/1.wav
  463. 2016-12-20 15:59:31.755424 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_do_a_freeswitch_echo_test.wav] (en:en)
  464. 2016-12-20 15:59:31.775441 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  465. 2016-12-20 15:59:33.575401 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_do_a_freeswitch_echo_test.wav
  466. 2016-12-20 15:59:33.695401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  467. 2016-12-20 15:59:33.715439 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  468. 2016-12-20 15:59:34.115399 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  469. 2016-12-20 15:59:34.235400 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  470. 2016-12-20 15:59:34.255439 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  471. 2016-12-20 15:59:34.635398 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  472. 2016-12-20 15:59:34.755400 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/2.wav] (en:en)
  473. 2016-12-20 15:59:34.775438 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  474. 2016-12-20 15:59:35.155400 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/2.wav
  475. 2016-12-20 15:59:35.295399 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_listen_to_moh.wav] (en:en)
  476. 2016-12-20 15:59:35.315444 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  477. 2016-12-20 15:59:36.875400 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_listen_to_moh.wav
  478. 2016-12-20 15:59:37.015405 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  479. 2016-12-20 15:59:37.035444 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  480. 2016-12-20 15:59:37.435400 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  481. 2016-12-20 15:59:37.555405 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  482. 2016-12-20 15:59:37.575435 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  483. 2016-12-20 15:59:37.955406 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  484. 2016-12-20 15:59:38.095399 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/3.wav] (en:en)
  485. 2016-12-20 15:59:38.115456 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  486. 2016-12-20 15:59:38.495403 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/3.wav
  487. 2016-12-20 15:59:38.615401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-register_for_cluecon.wav] (en:en)
  488. 2016-12-20 15:59:38.635440 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  489. 2016-12-20 15:59:41.055332 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-register_for_cluecon.wav
  490. 2016-12-20 15:59:41.155401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/4.wav] (en:en)
  491. 2016-12-20 15:59:41.175389 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  492. 2016-12-20 15:59:41.595337 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/4.wav
  493. 2016-12-20 15:59:41.735336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_hear_screaming_monkeys.wav] (en:en)
  494. 2016-12-20 15:59:41.735336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  495. 2016-12-20 15:59:43.215390 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_hear_screaming_monkeys.wav
  496. 2016-12-20 15:59:43.335414 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  497. 2016-12-20 15:59:43.355392 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  498. 2016-12-20 15:59:43.755364 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  499. 2016-12-20 15:59:43.895333 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  500. 2016-12-20 15:59:43.915393 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  501. 2016-12-20 15:59:44.295367 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  502. 2016-12-20 15:59:44.435389 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/5.wav] (en:en)
  503. 2016-12-20 15:59:44.455336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  504. 2016-12-20 15:59:44.975334 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/5.wav
  505. 2016-12-20 15:59:45.095398 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_hear_sample_submenu.wav] (en:en)
  506. 2016-12-20 15:59:45.115334 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  507. 2016-12-20 15:59:47.495334 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_hear_sample_submenu.wav
  508. 2016-12-20 15:59:47.595336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  509. 2016-12-20 15:59:47.615435 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  510. 2016-12-20 15:59:48.035332 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  511. 2016-12-20 15:59:48.135332 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  512. 2016-12-20 15:59:48.155332 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  513. 2016-12-20 15:59:48.555336 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  514. 2016-12-20 15:59:48.655337 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/6.wav] (en:en)
  515. 2016-12-20 15:59:48.655337 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  516. 2016-12-20 15:59:49.175405 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/6.wav
  517. 2016-12-20 15:59:49.335337 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_repeat_these_options.wav] (en:en)
  518. 2016-12-20 15:59:49.335337 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  519. 2016-12-20 15:59:50.535367 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_repeat_these_options.wav
  520. 2016-12-20 15:59:50.655410 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  521. 2016-12-20 15:59:50.675402 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  522. 2016-12-20 15:59:51.075388 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  523. 2016-12-20 15:59:51.195393 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  524. 2016-12-20 15:59:51.215390 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  525. 2016-12-20 15:59:51.595391 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  526. 2016-12-20 15:59:51.715409 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/9.wav] (en:en)
  527. 2016-12-20 15:59:51.735395 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  528. 2016-12-20 15:59:52.155389 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/9.wav
  529. 2016-12-20 15:59:52.295392 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[silence_stream://2000] (en:en)
  530. 2016-12-20 15:59:52.315389 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  531. 2016-12-20 15:59:53.875404 [DEBUG] switch_rtp.c:6092 RTP RECV DTMF 9:560
  532. 2016-12-20 15:59:53.875404 [DEBUG] switch_channel.c:488 RECV DTMF 9:560
  533. 2016-12-20 15:59:53.895392 [DEBUG] switch_ivr_play_say.c:1747 done playing file silence_stream://2000
  534. 2016-12-20 15:59:54.035388 [DEBUG] switch_ivr_menu.c:363 waiting for 3/4 digits t/o 2000
  535. 2016-12-20 15:59:56.035412 [DEBUG] switch_ivr_menu.c:410 digits '9'
  536. 2016-12-20 15:59:56.055334 [DEBUG] switch_ivr_menu.c:543 action regex [9] [/^(10[01][0-9])$/] [0]
  537. 2016-12-20 15:59:56.055334 [DEBUG] switch_ivr_menu.c:561 IVR action on menu 'demo_ivr' matched '9' param ''
  538. 2016-12-20 15:59:56.055334 [DEBUG] switch_ivr_menu.c:565 switch_ivr_menu_execute todo=[5]
  539. 2016-12-20 15:59:56.055334 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  540. 2016-12-20 15:59:56.055334 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[silence_stream://1000] (en:en)
  541. 2016-12-20 15:59:56.055334 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  542. 2016-12-20 15:59:57.035402 [DEBUG] switch_ivr_play_say.c:1747 done playing file silence_stream://1000
  543. 2016-12-20 15:59:57.155403 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_call_the_freeswitch_conference.wav] (en:en)
  544. 2016-12-20 15:59:57.175353 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  545. 2016-12-20 15:59:58.815335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_call_the_freeswitch_conference.wav
  546. 2016-12-20 15:59:58.935335 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  547. 2016-12-20 15:59:58.935335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  548. 2016-12-20 15:59:59.335336 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  549. 2016-12-20 15:59:59.455335 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  550. 2016-12-20 15:59:59.455335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  551. 2016-12-20 15:59:59.835335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  552. 2016-12-20 15:59:59.975333 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/1.wav] (en:en)
  553. 2016-12-20 15:59:59.995336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  554. 2016-12-20 16:00:00.455388 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/1.wav
  555. 2016-12-20 16:00:00.575332 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_do_a_freeswitch_echo_test.wav] (en:en)
  556. 2016-12-20 16:00:00.595393 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  557. 2016-12-20 16:00:02.415332 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_do_a_freeswitch_echo_test.wav
  558. 2016-12-20 16:00:02.535346 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  559. 2016-12-20 16:00:02.535346 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  560. 2016-12-20 16:00:02.955334 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  561. 2016-12-20 16:00:03.075336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  562. 2016-12-20 16:00:03.075336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  563. 2016-12-20 16:00:03.475335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  564. 2016-12-20 16:00:03.575336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/2.wav] (en:en)
  565. 2016-12-20 16:00:03.575336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  566. 2016-12-20 16:00:03.955345 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/2.wav
  567. 2016-12-20 16:00:04.075333 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_listen_to_moh.wav] (en:en)
  568. 2016-12-20 16:00:04.075333 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  569. 2016-12-20 16:00:05.655335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_listen_to_moh.wav
  570. 2016-12-20 16:00:05.755390 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  571. 2016-12-20 16:00:05.775388 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  572. 2016-12-20 16:00:06.175335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  573. 2016-12-20 16:00:06.315405 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  574. 2016-12-20 16:00:06.315405 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  575. 2016-12-20 16:00:06.715336 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  576. 2016-12-20 16:00:06.815390 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/3.wav] (en:en)
  577. 2016-12-20 16:00:06.835400 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  578. 2016-12-20 16:00:07.235345 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/3.wav
  579. 2016-12-20 16:00:07.355341 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_hear_sample_submenu.wav] (en:en)
  580. 2016-12-20 16:00:07.355341 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  581. 2016-12-20 16:00:09.715390 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_hear_sample_submenu.wav
  582. 2016-12-20 16:00:09.855400 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  583. 2016-12-20 16:00:09.875390 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  584. 2016-12-20 16:00:10.275335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  585. 2016-12-20 16:00:10.415399 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  586. 2016-12-20 16:00:10.435403 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  587. 2016-12-20 16:00:10.835336 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  588. 2016-12-20 16:00:10.935401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/4.wav] (en:en)
  589. 2016-12-20 16:00:10.955335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  590. 2016-12-20 16:00:11.395335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/4.wav
  591. 2016-12-20 16:00:11.515335 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_hear_screaming_monkeys.wav] (en:en)
  592. 2016-12-20 16:00:11.515335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  593. 2016-12-20 16:00:12.995336 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_hear_screaming_monkeys.wav
  594. 2016-12-20 16:00:13.135402 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  595. 2016-12-20 16:00:13.155400 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  596. 2016-12-20 16:00:13.555336 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  597. 2016-12-20 16:00:13.695336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  598. 2016-12-20 16:00:13.695336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  599. 2016-12-20 16:00:14.075335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  600. 2016-12-20 16:00:14.215399 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/5.wav] (en:en)
  601. 2016-12-20 16:00:14.235401 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  602. 2016-12-20 16:00:14.755335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/5.wav
  603. 2016-12-20 16:00:14.855399 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_repeat_these_options.wav] (en:en)
  604. 2016-12-20 16:00:14.875402 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  605. 2016-12-20 16:00:16.095335 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_repeat_these_options.wav
  606. 2016-12-20 16:00:16.195401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  607. 2016-12-20 16:00:16.215335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  608. 2016-12-20 16:00:16.635336 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  609. 2016-12-20 16:00:16.735401 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  610. 2016-12-20 16:00:16.755335 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  611. 2016-12-20 16:00:17.135408 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  612. 2016-12-20 16:00:17.255400 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/9.wav] (en:en)
  613. 2016-12-20 16:00:17.275403 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  614. 2016-12-20 16:00:17.695402 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/9.wav
  615. 2016-12-20 16:00:17.835336 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[silence_stream://2000] (en:en)
  616. 2016-12-20 16:00:17.835336 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  617. 2016-12-20 16:00:19.815401 [DEBUG] switch_ivr_play_say.c:1747 done playing file silence_stream://2000
  618. 2016-12-20 16:00:19.955398 [DEBUG] switch_ivr_menu.c:363 waiting for 4/4 digits t/o 2000
  619. 2016-12-20 16:00:21.675402 [DEBUG] switch_rtp.c:6092 RTP RECV DTMF 6:560
  620. 2016-12-20 16:00:21.675402 [DEBUG] switch_channel.c:488 RECV DTMF 6:560
  621. 2016-12-20 16:00:23.695407 [DEBUG] switch_ivr_menu.c:410 digits '6'
  622. 2016-12-20 16:00:23.715410 [DEBUG] switch_ivr_menu.c:561 IVR action on menu 'demo_ivr' matched '6' param 'demo_ivr_submenu'
  623. 2016-12-20 16:00:23.715410 [DEBUG] switch_ivr_menu.c:565 switch_ivr_menu_execute todo=[1]
  624. 2016-12-20 16:00:23.715410 [DEBUG] switch_ivr_menu.c:469 Executing IVR menu demo_ivr_submenu
  625. 2016-12-20 16:00:23.715410 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  626. 2016-12-20 16:00:23.715410 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-welcome_to_freeswitch.wav] (en:en)
  627. 2016-12-20 16:00:23.715410 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  628. 2016-12-20 16:00:26.315347 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-welcome_to_freeswitch.wav
  629. 2016-12-20 16:00:26.435402 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-sample_submenu.wav] (en:en)
  630. 2016-12-20 16:00:26.455347 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  631. 2016-12-20 16:00:32.695405 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-sample_submenu.wav
  632. 2016-12-20 16:00:32.815412 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_return_to_previous_menu.wav] (en:en)
  633. 2016-12-20 16:00:32.835402 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  634. 2016-12-20 16:00:34.395346 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_return_to_previous_menu.wav
  635. 2016-12-20 16:00:34.515400 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
  636. 2016-12-20 16:00:34.535400 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  637. 2016-12-20 16:00:34.935347 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
  638. 2016-12-20 16:00:35.055347 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  639. 2016-12-20 16:00:35.075401 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  640. 2016-12-20 16:00:35.455402 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  641. 2016-12-20 16:00:35.575413 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/star.wav] (en:en)
  642. 2016-12-20 16:00:35.595409 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
  643. 2016-12-20 16:00:36.075399 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/star.wav
  644. 2016-12-20 16:00:36.215399 [DEBUG] switch_ivr_menu.c:363 waiting for 1/1 digits t/o 7500
  645. recv 359 bytes from udp/[10.200.161.132]:5060 at 16:00:39.361417:
  646.    ------------------------------------------------------------------------
  647.    BYE sip:mod_sofia@10.227.47.13:5080 SIP/2.0
  648.    From: <sip:+2348124523790@10.200.161.132>;tag=4203370473
  649.    To: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  650.    Max-Forwards: 70
  651.    Via: SIP/2.0/UDP  10.200.161.132:5060;branch=z9hG4bK00000002864043090940
  652.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  653.    CSeq: 1 BYE
  654.    Reason: Q.850;cause=16
  655.    Content-Length:    0
  656.  
  657.    ------------------------------------------------------------------------
  658. 2016-12-20 16:00:39.355399 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  659. 2016-12-20 16:00:39.375390 [NOTICE] sofia.c:952 Hangup sofia/external/%2B2348124523790@10.200.161.132 [CS_EXECUTE] [NORMAL_CLEARING]
  660. 2016-12-20 16:00:39.375390 [DEBUG] switch_channel.c:3222 Send signal sofia/external/%2B2348124523790@10.200.161.132 [KILL]
  661. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  662. 2016-12-20 16:00:39.375390 [DEBUG] switch_ivr_menu.c:410 digits ''
  663. 2016-12-20 16:00:39.375390 [DEBUG] switch_ivr_menu.c:643 IVR menu 'demo_ivr_submenu' no input detected
  664. 2016-12-20 16:00:39.375390 [DEBUG] switch_ivr_menu.c:543 action regex [6] [/^(10[01][0-9])$/] [0]
  665. 2016-12-20 16:00:39.375390 [DEBUG] switch_ivr_menu.c:655 exit-sound 'voicemail/vm-goodbye.wav'
  666. send 456 bytes to udp/[10.200.161.132]:5060 at 16:00:39.376719:
  667.    ------------------------------------------------------------------------
  668.    SIP/2.0 200 OK
  669.    Via: SIP/2.0/UDP  10.200.161.132:5060;branch=z9hG4bK00000002864043090940
  670.    From: <sip:+2348124523790@10.200.161.132>;tag=4203370473
  671.    To: "55101" <sip:55101@10.227.47.13>;tag=U35jUNH2QU38H
  672.    Call-ID: 9ae5109e-4167-1235-c28a-000c293e4716
  673.    CSeq: 1 BYE
  674.    User-Agent: FreeSWITCH-mod_sofia/1.4.18~64bit
  675.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  676.    Supported: timer, path, replaces
  677.    Content-Length: 0
  678.  
  679.    ------------------------------------------------------------------------
  680. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_session.c:2901 sofia/external/%2B2348124523790@10.200.161.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  681. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:535 (sofia/external/%2B2348124523790@10.200.161.132) State EXECUTE going to sleep
  682. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:472 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_HANGUP
  683. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:735 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change ACTIVE -> HANGUP
  684. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:737 (sofia/external/%2B2348124523790@10.200.161.132) State HANGUP
  685. 2016-12-20 16:00:39.375390 [DEBUG] mod_sofia.c:413 Channel sofia/external/%2B2348124523790@10.200.161.132 hanging up, cause: NORMAL_CLEARING
  686. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:60 sofia/external/%2B2348124523790@10.200.161.132 Standard HANGUP, cause: NORMAL_CLEARING
  687. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:737 (sofia/external/%2B2348124523790@10.200.161.132) State HANGUP going to sleep
  688. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:504 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_HANGUP -> CS_REPORTING
  689. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  690. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:472 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_REPORTING
  691. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:823 (sofia/external/%2B2348124523790@10.200.161.132) State REPORTING
  692. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:104 sofia/external/%2B2348124523790@10.200.161.132 Standard REPORTING, cause: NORMAL_CLEARING
  693. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:823 (sofia/external/%2B2348124523790@10.200.161.132) State REPORTING going to sleep
  694. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:498 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_REPORTING -> CS_DESTROY
  695. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/%2B2348124523790@10.200.161.132 [BREAK]
  696. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_session.c:1623 Session 6 (sofia/external/%2B2348124523790@10.200.161.132) Locked, Waiting on external entities
  697. 2016-12-20 16:00:39.375390 [NOTICE] switch_core_session.c:1641 Session 6 (sofia/external/%2B2348124523790@10.200.161.132) Ended
  698. 2016-12-20 16:00:39.375390 [NOTICE] switch_core_session.c:1645 Close Channel sofia/external/%2B2348124523790@10.200.161.132 [CS_DESTROY]
  699. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:626 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_DESTROY
  700. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:636 (sofia/external/%2B2348124523790@10.200.161.132) State DESTROY
  701. 2016-12-20 16:00:39.375390 [DEBUG] mod_sofia.c:323 sofia/external/%2B2348124523790@10.200.161.132 SOFIA DESTROY
  702. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:111 sofia/external/%2B2348124523790@10.200.161.132 Standard DESTROY
  703. 2016-12-20 16:00:39.375390 [DEBUG] switch_core_state_machine.c:636 (sofia/external/%2B2348124523790@10.200.161.132) State DESTROY going to sleep
  704.  
  705.