361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [NOTICE] switch_channel.c:1055 New Channel sofia/internal/1001@54.78.27.234 [361a043f-31e0-42ad-8b1e-74ae64e250d8]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1001@54.78.27.234) Running State Change CS_NEW
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] sofia.c:8777 sofia/internal/1001@54.78.27.234 receiving invite from 27.251.76.204:49606 version: 1.5.15b git 63734bc 2014-10-08 20:45:20Z 64bit
2014-10-09 13:34:16.947515 [DEBUG] sofia.c:8889 IP 27.251.76.204 Approved by acl "domains[1001@10.120.8.106]". Access Granted.
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] sofia.c:9020 Authenticating user 1001@10.120.8.106
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] sofia.c:6571 Channel sofia/internal/1001@54.78.27.234 entering state [received][100]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] sofia.c:6581 Remote SDP:
361a043f-31e0-42ad-8b1e-74ae64e250d8 v=0
361a043f-31e0-42ad-8b1e-74ae64e250d8 o=- 3 2 IN IP4 192.168.1.163
361a043f-31e0-42ad-8b1e-74ae64e250d8 s=CounterPath X-Lite 3.0
361a043f-31e0-42ad-8b1e-74ae64e250d8 c=IN IP4 192.168.1.163
361a043f-31e0-42ad-8b1e-74ae64e250d8 t=0 0
361a043f-31e0-42ad-8b1e-74ae64e250d8 m=audio 19522 RTP/AVP 107 0 8 101
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:107 BV32/16000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:101 telephone-event/8000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=fmtp:101 0-15
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:1 2 : dKTAM1jj yggrcoEL 192.168.56.1 19522
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:2 1 : JNgDiKiN 9ZAXJf36 192.168.1.163 19522
361a043f-31e0-42ad-8b1e-74ae64e250d8 m=video 60454 RTP/AVP 115 34
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:115 H263-1998/90000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=fmtp:115 QCIF=1;CIF=1;I=1;J=1;T=1
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:34 H263/90000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=fmtp:34 QCIF=1;CIF=1
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:1 2 : DSb3aJeG p67jt2CQ 192.168.56.1 60454
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:2 1 : usWRHNft is4xegAM 192.168.1.163 60454
361a043f-31e0-42ad-8b1e-74ae64e250d8 
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] sofia.c:6833 (sofia/internal/1001@54.78.27.234) State Change CS_NEW -> CS_INIT
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1001@54.78.27.234) State NEW
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1001@54.78.27.234) Running State Change CS_INIT
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/1001@54.78.27.234) State INIT
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] mod_sofia.c:87 sofia/internal/1001@54.78.27.234 SOFIA INIT
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@54.78.27.234 Standard INIT
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@54.78.27.234) State Change CS_INIT -> CS_ROUTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/1001@54.78.27.234) State INIT going to sleep
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1001@54.78.27.234) Running State Change CS_ROUTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_channel.c:2184 (sofia/internal/1001@54.78.27.234) Callstate Change DOWN -> RINGING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/1001@54.78.27.234) State ROUTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] mod_sofia.c:123 sofia/internal/1001@54.78.27.234 SOFIA ROUTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1001@54.78.27.234 Standard ROUTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [INFO] mod_dialplan_xml.c:558 Processing Sanjay <1001>->5000 in context default
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->unloop] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->tod_example] continue=true
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Date/Time Match (PASS) [tod_example] break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action set(open=true)
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->holiday_example] continue=true
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Date/TimeMatch (FAIL) [holiday_example] break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->global-intercept] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [global-intercept] destination_number(5000) =~ /^886$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->group-intercept] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [group-intercept] destination_number(5000) =~ /^\*8$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->intercept-ext] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [intercept-ext] destination_number(5000) =~ /^\*\*(\d+)$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->redial] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [redial] destination_number(5000) =~ /^(redial|870)$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->global] continue=true
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [global] ${default_password}(fs_qpay) =~ /^1234$/ break=never
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 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
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [global] ${switch_r_sdp}(v=0
361a043f-31e0-42ad-8b1e-74ae64e250d8 o=- 3 2 IN IP4 192.168.1.163
361a043f-31e0-42ad-8b1e-74ae64e250d8 s=CounterPath X-Lite 3.0
361a043f-31e0-42ad-8b1e-74ae64e250d8 c=IN IP4 192.168.1.163
361a043f-31e0-42ad-8b1e-74ae64e250d8 t=0 0
361a043f-31e0-42ad-8b1e-74ae64e250d8 m=audio 19522 RTP/AVP 107 0 8 101
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:107 BV32/16000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:101 telephone-event/8000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=fmtp:101 0-15
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:1 2 : dKTAM1jj yggrcoEL 192.168.56.1 19522
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:2 1 : JNgDiKiN 9ZAXJf36 192.168.1.163 19522
361a043f-31e0-42ad-8b1e-74ae64e250d8 m=video 60454 RTP/AVP 115 34
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:115 H263-1998/90000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=fmtp:115 QCIF=1;CIF=1;I=1;J=1;T=1
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:34 H263/90000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=fmtp:34 QCIF=1;CIF=1
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:1 2 : DSb3aJeG p67jt2CQ 192.168.56.1 60454
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=alt:2 1 : usWRHNft is4xegAM 192.168.1.163 60454
361a043f-31e0-42ad-8b1e-74ae64e250d8 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Absolute Condition [global]
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action hash(insert/${domain_name}-last_dial/global/${uuid})
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->snom-demo-2] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [snom-demo-2] destination_number(5000) =~ /^9001$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->snom-demo-1] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [snom-demo-1] destination_number(5000) =~ /^9000$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->eavesdrop] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [eavesdrop] destination_number(5000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->eavesdrop] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [eavesdrop] destination_number(5000) =~ /^779$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->call_return] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [call_return] destination_number(5000) =~ /^\*69$|^869$|^lcr$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->del-group] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [del-group] destination_number(5000) =~ /^80(\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->add-group] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [add-group] destination_number(5000) =~ /^81(\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->call-group-simo] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [call-group-simo] destination_number(5000) =~ /^82(\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->call-group-order] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [call-group-order] destination_number(5000) =~ /^83(\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->extension-intercom] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [extension-intercom] destination_number(5000) =~ /^8(10[01][0-9])$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->Local_Extension] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [Local_Extension] destination_number(5000) =~ /^(10[01][0-9])$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->Local_Extension_Skinny] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [Local_Extension_Skinny] destination_number(5000) =~ /^(11[01][0-9])$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->group_dial_sales] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [group_dial_sales] destination_number(5000) =~ /^2000$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->group_dial_support] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [group_dial_support] destination_number(5000) =~ /^2001$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->group_dial_billing] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [group_dial_billing] destination_number(5000) =~ /^2002$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->operator] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [operator] destination_number(5000) =~ /^(operator|0)$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->vmain] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [vmain] destination_number(5000) =~ /^vmain$|^4000$|^\*98$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->sip_uri] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [sip_uri] destination_number(5000) =~ /^sip:(.*)$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->nb_conferences] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [nb_conferences] destination_number(5000) =~ /^(30\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->wb_conferences] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [wb_conferences] destination_number(5000) =~ /^(31\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->uwb_conferences] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [uwb_conferences] destination_number(5000) =~ /^(32\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->cdquality_conferences] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [cdquality_conferences] destination_number(5000) =~ /^(33\d{2})$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->freeswitch_public_conf_via_sip] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(5000) =~ /^9(888|8888|1616|3232)$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->mad_boss_intercom] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [mad_boss_intercom] destination_number(5000) =~ /^0911$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->mad_boss_intercom] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [mad_boss_intercom] destination_number(5000) =~ /^0912$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->mad_boss] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (FAIL) [mad_boss] destination_number(5000) =~ /^0913$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 parsing [default->ivr_demo] continue=false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Regex (PASS) [ivr_demo] destination_number(5000) =~ /^5000$/ break=on-false
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action answer()
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action sleep(2000)
361a043f-31e0-42ad-8b1e-74ae64e250d8 Dialplan: sofia/internal/1001@54.78.27.234 Action ivr(demo_ivr)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1001@54.78.27.234) State Change CS_ROUTING -> CS_EXECUTE
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.947515 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/1001@54.78.27.234) State ROUTING going to sleep
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1001@54.78.27.234) Running State Change CS_EXECUTE
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/1001@54.78.27.234) State EXECUTE
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] mod_sofia.c:178 sofia/internal/1001@54.78.27.234 SOFIA EXECUTE
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1001@54.78.27.234 Standard EXECUTE
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 set(open=true)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] mod_dptools.c:1435 sofia/internal/1001@54.78.27.234 SET [open]=[true]
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 hash(insert/10.120.8.106-spymap/1001/361a043f-31e0-42ad-8b1e-74ae64e250d8)
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 hash(insert/10.120.8.106-last_dial/1001/5000)
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 hash(insert/10.120.8.106-last_dial/global/361a043f-31e0-42ad-8b1e-74ae64e250d8)
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 export(RFC2822_DATE=Thu, 09 Oct 2014 13:34:16 +0000)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 09 Oct 2014 13:34:16 +0000]
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 answer()
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [BV32:107:16000:20:0:1]/[opus:116:48000:20:0:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [BV32:107:16000:20:0:1]/[G722:9:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [BV32:107:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [BV32:107:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [BV32:107:16000:20:0:1]/[GSM:3:8000:20:13200:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3544 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3544 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3489 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3405 Set telephone-event payload to 101
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:2361 Set Codec sofia/internal/1001@54.78.27.234 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@54.78.27.234 Original read codec set to PCMU:0
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:3735 Set 2833 dtmf send/recv payload to 101
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:4970 AUDIO RTP [sofia/internal/1001@54.78.27.234] 10.120.8.106 port 22314 -> 192.168.1.163 port 19522 codec: 0 ms: 20
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_rtp.c:3521 Starting timer [soft] 160 bytes per 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:5322 Set 2833 dtmf send payload to 101
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_media.c:5328 Set 2833 dtmf receive payload to 101
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@54.78.27.234!
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_channel.c:3399 (sofia/internal/1001@54.78.27.234) Callstate Change RINGING -> EARLY
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] mod_sofia.c:780 Local SDP sofia/internal/1001@54.78.27.234:
361a043f-31e0-42ad-8b1e-74ae64e250d8 v=0
361a043f-31e0-42ad-8b1e-74ae64e250d8 o=FreeSWITCH 1412839342 1412839343 IN IP4 10.120.8.106
361a043f-31e0-42ad-8b1e-74ae64e250d8 s=FreeSWITCH
361a043f-31e0-42ad-8b1e-74ae64e250d8 c=IN IP4 10.120.8.106
361a043f-31e0-42ad-8b1e-74ae64e250d8 t=0 0
361a043f-31e0-42ad-8b1e-74ae64e250d8 m=audio 22314 RTP/AVP 0 101
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:0 PCMU/8000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=rtpmap:101 telephone-event/8000
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=fmtp:101 0-16
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=ptime:20
361a043f-31e0-42ad-8b1e-74ae64e250d8 a=sendrecv
361a043f-31e0-42ad-8b1e-74ae64e250d8 
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [NOTICE] mod_dptools.c:1258 Channel [sofia/internal/1001@54.78.27.234] has been answered
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] switch_channel.c:3689 (sofia/internal/1001@54.78.27.234) Callstate Change EARLY -> ACTIVE
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:16.967542 [DEBUG] sofia.c:6571 Channel sofia/internal/1001@54.78.27.234 entering state [completed][200]
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 sleep(2000)
361a043f-31e0-42ad-8b1e-74ae64e250d8 EXECUTE sofia/internal/1001@54.78.27.234 ivr(demo_ivr)
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-exit'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-sub'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-back'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:721 switch_ivr_menu_stack_xml_add binding 'menu-top'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:858 building menu 'demo_ivr'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '1'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '2'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '3'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '4'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '5'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:858 building menu 'demo_ivr_submenu'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-top' to '*'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-sub' to '6'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-exec-app' to '/^(10[01][0-9])$/'
2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:942 binding menu action 'menu-top' to '9'
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_menu.c:469 Executing IVR menu demo_ivr
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-welcome_to_freeswitch.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:18.987514 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:21.607514 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-welcome_to_freeswitch.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:21.707514 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-this_ivr_will_let_you_test_features.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:21.707514 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:25.687515 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-this_ivr_will_let_you_test_features.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:25.787515 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-you_may_exit_by_hanging_up.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:25.787515 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:28.387515 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-you_may_exit_by_hanging_up.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:28.507515 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-enter_ext_pound.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:28.507515 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:31.187516 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-enter_ext_pound.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:31.287518 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[silence_stream://1500] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:31.287518 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:32.787515 [DEBUG] switch_ivr_play_say.c:1720 done playing file silence_stream://1500
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:32.907515 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_call_the_freeswitch_conference.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:32.907515 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:34.547516 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_call_the_freeswitch_conference.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:34.647516 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:34.647516 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:35.067516 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:35.167516 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:35.167516 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:35.567516 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:35.667516 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/1.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:35.667516 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:36.127515 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/1.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:36.227516 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_do_a_freeswitch_echo_test.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:36.227516 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:38.047516 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_do_a_freeswitch_echo_test.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:38.147517 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:38.147517 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:38.567520 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:38.687515 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:38.687515 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:39.087516 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:39.187516 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/2.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:39.187516 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:39.587517 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/2.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:39.707516 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_listen_to_moh.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:39.707516 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:41.287514 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_listen_to_moh.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:41.387514 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:41.387514 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:41.807516 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:41.907518 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:41.907518 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:42.307515 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:42.427514 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/3.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:42.427514 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:42.827514 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/3.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:42.927514 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-register_for_cluecon.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:42.927514 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:45.347515 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-register_for_cluecon.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:45.447557 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/4.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:45.447557 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:45.887514 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/4.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:45.987515 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-to_hear_screaming_monkeys.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:45.987515 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:47.487514 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-to_hear_screaming_monkeys.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:47.587515 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[ivr/ivr-please.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:47.587515 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.007515 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-please.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.127514 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.127514 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.527514 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.627515 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[digits/5.wav] (en:en)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.627515 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.967515 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.967515 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] sofia.c:6571 Channel sofia/internal/1001@54.78.27.234 entering state [terminating][0]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [NOTICE] sofia.c:7473 Hangup sofia/internal/1001@54.78.27.234 [CS_EXECUTE] [NORMAL_UNSPECIFIED]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_channel.c:3222 Send signal sofia/internal/1001@54.78.27.234 [KILL]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_ivr_play_say.c:1720 done playing file /usr/local/freeswitch/sounds/en/us/callie/digits/5.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_ivr_menu.c:363 waiting for 4/4 digits t/o 2000
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_ivr_menu.c:410 digits 
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_ivr_menu.c:643 IVR menu demo_ivr no input detected
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_ivr_menu.c:655 exit-sound voicemail/vm-goodbye.wav
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_session.c:2888 sofia/internal/1001@54.78.27.234 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/1001@54.78.27.234) State EXECUTE going to sleep
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1001@54.78.27.234) Running State Change CS_HANGUP
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/1001@54.78.27.234) Callstate Change ACTIVE -> HANGUP
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/1001@54.78.27.234) State HANGUP
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] mod_sofia.c:413 Channel sofia/internal/1001@54.78.27.234 hanging up, cause: NORMAL_UNSPECIFIED
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@54.78.27.234 Standard HANGUP, cause: NORMAL_UNSPECIFIED
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/1001@54.78.27.234) State HANGUP going to sleep
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/1001@54.78.27.234) State Change CS_HANGUP -> CS_REPORTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1001@54.78.27.234) Running State Change CS_REPORTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/1001@54.78.27.234) State REPORTING
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1001@54.78.27.234 Standard REPORTING, cause: NORMAL_UNSPECIFIED
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/1001@54.78.27.234) State REPORTING going to sleep
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:498 (sofia/internal/1001@54.78.27.234) State Change CS_REPORTING -> CS_DESTROY
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/1001@54.78.27.234 [BREAK]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_session.c:1615 Session 2 (sofia/internal/1001@54.78.27.234) Locked, Waiting on external entities
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [NOTICE] switch_core_session.c:1633 Session 2 (sofia/internal/1001@54.78.27.234) Ended
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [NOTICE] switch_core_session.c:1637 Close Channel sofia/internal/1001@54.78.27.234 [CS_DESTROY]
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:626 (sofia/internal/1001@54.78.27.234) Running State Change CS_DESTROY
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/1001@54.78.27.234) State DESTROY
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] mod_sofia.c:323 sofia/internal/1001@54.78.27.234 SOFIA DESTROY
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1001@54.78.27.234 Standard DESTROY
361a043f-31e0-42ad-8b1e-74ae64e250d8 2014-10-09 13:34:48.987519 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/1001@54.78.27.234) State DESTROY going to sleep