2016-02-13 09:22:50.747900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/Master.csv
2016-02-13 09:22:50.747900 [NOTICE] mod_logfile.c:213 New log started.

a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.157920 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1276@domain.example.com [a17a5d15-d97e-4c70-b476-bd0ff4ac2e09]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.157920 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_NEW
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.157920 [DEBUG] sofia.c:9248 sofia/internal/1276@domain.example.com receiving invite from 68.5.94.63:12113 version: 1.6.6  64bit
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.157920 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.157920 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.157920 [ALERT] switch_core_media.c:370 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
2016-02-13 09:23:10.157920 [DEBUG] sofia.c:9415 IP 68.5.94.63 Rejected by acl "domains". Falling back to Digest auth.
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.157920 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1276@domain.example.com) State NEW
2016-02-13 09:23:10.157920 [DEBUG] sofia.c:2147 detaching session a17a5d15-d97e-4c70-b476-bd0ff4ac2e09
2016-02-13 09:23:10.387894 [DEBUG] sofia.c:2255 Re-attaching to session a17a5d15-d97e-4c70-b476-bd0ff4ac2e09
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.397894 [DEBUG] sofia.c:9248 sofia/internal/1276@domain.example.com receiving invite from 68.5.94.63:12113 version: 1.6.6  64bit
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.397894 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.397894 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.397894 [ALERT] switch_core_media.c:370 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
2016-02-13 09:23:10.397894 [DEBUG] sofia.c:9415 IP 68.5.94.63 Rejected by acl "domains". Falling back to Digest auth.
2016-02-13 09:23:10.397894 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:23:10.407800 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] sofia.c:10549 Setting NAT mode based on nat.auto
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@domain.example.com entering state [received][100]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] sofia.c:6770 Remote SDP:
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 v=0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 o=1276 21012 1 IN IP4 192.168.1.168
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 s=-
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 c=IN IP4 192.168.1.168
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 t=0 0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 m=audio 8002 RTP/AVP 0 18 8 96
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:0 PCMU/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:18 G729/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:8 PCMA/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:96 telephone-event/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=ptime:20
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] sofia.c:7125 (sofia/internal/1276@domain.example.com) State Change CS_NEW -> CS_INIT
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_INIT
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1276@domain.example.com) State INIT
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] mod_sofia.c:88 sofia/internal/1276@domain.example.com SOFIA INIT
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1276@domain.example.com Standard INIT
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1276@domain.example.com) State Change CS_INIT -> CS_ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1276@domain.example.com) State INIT going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_channel.c:2247 (sofia/internal/1276@domain.example.com) Callstate Change DOWN -> RINGING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1276@domain.example.com) State ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] mod_sofia.c:141 sofia/internal/1276@domain.example.com SOFIA ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1276@domain.example.com Standard ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [INFO] mod_dialplan_xml.c:637 Processing Kunal Mittal <1276>->142 in context public
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [public->limit_exceeded] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [limit_exceeded] destination_number(142) =~ /^limit_exceeded$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [public->from_testopensip] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [from_testopensip] network_addr(68.5.94.63) =~ /^209\.216\.15\.18$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [public->from_productionopensip] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [from_productionopensip] network_addr(68.5.94.63) =~ /^209\.216\.2\.222$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [public->from_registeredusers] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Absolute Condition [from_registeredusers]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(dialed_domain=${sip_to_host})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(domain_name=${sip_to_host})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(company_name=${sip_to_host})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action export(caller-id-in-from=true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action export(sip_cid_type=none)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action log(Public IP is ${bind_server_ip} Domain is ${domain_name} Dialed domain is ${dialed_domain})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action lua(didhandle.lua ${destination_number})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action log(INFO public Local URI var_name chan var is ${domain_name} Dialed domain is ${dialed_domain})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1276@domain.example.com) State Change CS_ROUTING -> CS_EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1276@domain.example.com) State ROUTING going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1276@domain.example.com) State EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] mod_sofia.c:196 sofia/internal/1276@domain.example.com SOFIA EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1276@domain.example.com Standard EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(dialed_domain=domain.example.com)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [dialed_domain]=[domain.example.com]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(domain_name=domain.example.com)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [domain_name]=[domain.example.com]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(company_name=domain.example.com)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [company_name]=[domain.example.com]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(caller-id-in-from=true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [caller-id-in-from]=[true]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(sip_cid_type=none)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [sip_cid_type]=[none]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com log(Public IP is 219.206.20.22 Domain is domain.example.com Dialed domain is domain.example.com)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] mod_dptools.c:1692 IP is 219.206.20.22 Domain is domain.example.com Dialed domain is domain.example.com
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com lua(didhandle.lua 142)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
2016-02-13 09:23:10.407800 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(domain_name=domain.example.com)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [domain_name]=[domain.example.com]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(skip_cdr_causes=LOSE_RACE)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [skip_cdr_causes]=[LOSE_RACE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [INFO] switch_cpp.cpp:1284 RPID is  dialing number is 1276
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(accountcode=17)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [accountcode]=[17]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.407800 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [INFO] switch_cpp.cpp:1284 String length is less that 7 should be extension
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(process_cdr=a_only)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [process_cdr]=[a_only]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_ivr.c:2085 (sofia/internal/1276@domain.example.com) State Change CS_EXECUTE -> CS_ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_ivr.c:2090 sofia/internal/1276@domain.example.com receive message [TRANSFER]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [NOTICE] switch_ivr.c:2092 Transfer sofia/internal/1276@domain.example.com to XML[142@default]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_cpp.cpp:898 transfer result: 0
2016-02-13 09:23:10.417792 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_cpp.cpp:1103 sofia/internal/1276@domain.example.com destroy/unlink session from object
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1276@domain.example.com) State EXECUTE going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1276@domain.example.com) State ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_sofia.c:141 sofia/internal/1276@domain.example.com SOFIA ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1276@domain.example.com Standard ROUTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [INFO] mod_dialplan_xml.c:637 Processing Kunal Mittal <1276>->142 in context default
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->longdistance] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [longdistance] destination_number(142) =~ /^\d{10,15}$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->unloop] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->freeswitch_public_conf_via_sip] continue=false
2016-02-13 09:23:10.417792 [ERR] switch_regex.c:104 COMPILE ERROR: 1 [nothing to repeat][^*9(888|8888|1616|3232)$]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(142) =~ /^*9(888|8888|1616|3232)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->tod_example] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Date/TimeMatch (FAIL) [tod_example] break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->holiday_example] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Date/TimeMatch (FAIL) [holiday_example] break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->global-intercept] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [global-intercept] destination_number(142) =~ /^\*886$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->group-intercept] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [group-intercept] destination_number(142) =~ /^\*8$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->intercept-ext] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [intercept-ext] destination_number(142) =~ /^\*\*(\d+)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->redial] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [redial] destination_number(142) =~ /^\*(redial|870)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->global] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [global] ${switch_r_sdp}(v=0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 o=1276 21012 1 IN IP4 192.168.1.168
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 s=-
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 c=IN IP4 192.168.1.168
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 t=0 0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 m=audio 8002 RTP/AVP 0 18 8 96
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:0 PCMU/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:18 G729/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:8 PCMA/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:96 telephone-event/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=ptime:20
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Absolute Condition [global]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action hash(insert/${domain_name}-last_dial/global/${uuid})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->snom-demo-2] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [snom-demo-2] destination_number(142) =~ /^9001$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->snom-demo-1] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [snom-demo-1] destination_number(142) =~ /^9000$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->eavesdrop] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [eavesdrop] destination_number(142) =~ /^\*88(\d{3,5})$|^\*0(.*)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->eavesdrop] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [eavesdrop] destination_number(142) =~ /^\*779$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->call_return] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [call_return] destination_number(142) =~ /^\*69$|^\*869$|^lcr$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->del-number] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [del-number] destination_number(142) =~ /^\*60$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->add-number] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [add-number] destination_number(142) =~ /^\*61$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->check-number] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [check-number] destination_number(142) =~ /^\*62$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->enable-time-condition] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [enable-time-condition] destination_number(142) =~ /^\*63$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->disable-time-condition] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [disable-time-condition] destination_number(142) =~ /^\*64$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->del-group] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [del-group] destination_number(142) =~ /^\*80$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->add-group] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [add-group] destination_number(142) =~ /^\*81$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->call-group-simo] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [call-group-simo] destination_number(142) =~ /^\*82(\d{2})$|^82(\d{2})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->call-group-order] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [call-group-order] destination_number(142) =~ /^\*83(\d{2})$|^83(\d{2})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->extension-intercom] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [extension-intercom] destination_number(142) =~ /^\*84(\d{3,5})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->user_recodring_enabled] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [user_recodring_enabled] ${recording}(off) =~ /^(on|off)$/ break=on-true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->user_callscreening] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [user_callscreening] caller_id_number(1276) =~ /^\d{10,15}$/ break=on-true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [user_callscreening] ${callscreening}(off) =~ /^(on|off)$/ break=on-true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Check IVR-based CF] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [Check IVR-based CF] destination_number(142) =~ /^(\d+)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(dialed_number=142) INLINE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(dialed_number=142)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [dialed_number]=[142]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(cf_target=${db(select/${domain_name}-CF/142)}) INLINE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(cf_target=)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [cf_target]=[UNDEF]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Check IVR-based CF] ${recording}(off) =~ /^on$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Check IVR-based CF] ${cf_target}() =~ /^\d{3,5}$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Check IVR-based CF] ${cf_target}() =~ /^\d{10,15}$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->CF from my station] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [CF from my station] destination_number(142) =~ /^\*72$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->CF cancel from my station] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [CF cancel from my station] destination_number(142) =~ /^\*73$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->CF from any station] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [CF from any station] destination_number(142) =~ /^\*76$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->CF cancel from any station] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [CF cancel from any station] destination_number(142) =~ /^\*77$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->NACF from my station] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [NACF from my station] destination_number(142) =~ /^\*74$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->NACF cancel from my station] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [NACF cancel from my station] destination_number(142) =~ /^\*75$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Add_Member_To_Queue] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Add_Member_To_Queue] destination_number(142) =~ /^\*51$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Delete_Member_From_Queue] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Delete_Member_From_Queue] destination_number(142) =~ /^\*52$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Pickup_Call_From_Queue] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Pickup_Call_From_Queue] destination_number(142) =~ /^\*53$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->dial-by-name] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [dial-by-name] destination_number(142) =~ /^\*40$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->conference_withpin] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [conference_withpin] destination_number(142) =~ /^\*3560$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Voicemail_check] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Voicemail_check] caller_id_number(1276) =~ /^(142)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->check_availability] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [check_availability] destination_number(142) =~ /^hkjhk(1[0-9][0-9][0-9]|[1-2][0-9][0-9])$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->is_check_availability] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [is_check_availability] available_to_call() =~ /true/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->osbridge] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [osbridge] destination_number(142) =~ /(^\d{3,5})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(proxy_media=false)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(continue_on_fail=true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action export(dialed_extension=142) INLINE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(dialed_extension=142)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [dialed_extension]=[142]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action export(domain_name=${domain_name})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(accountcode=${user_data(${dialed_extension}@${domain_name} var accountcode)})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action export(available_to_call=true) INLINE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(available_to_call=true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [available_to_call]=[true]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action log(Extensions ${dialed_extension} is ${available_to_call})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(nacf_target=${db(select/${domain_name}-NACF/142)}) INLINE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(nacf_target=)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [nacf_target]=[UNDEF]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [osbridge] ${recording}(off) =~ /^on$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [osbridge] ${available_to_call}(true) =~ /^true$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bind_meta_app(1 b s execute_extension::dx XML features)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/var/lib/freeswitch/recordings/${domain_name}/${strftime(%Y-%m-%d-%H-%M-%S)}.${uuid}.wav)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bind_meta_app(3 b s execute_extension::cf XML features)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bind_meta_app(4 b s execute_extension::attented_xfer XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bind_meta_app(5 b s execute_extension::attented_xfer_to_vm XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bind_meta_app(6 b s execute_extension::blind_xfer_to XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bind_meta_app(9 b s execute_extension::call_park XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action export(sip_contact_user=${sip_from_user})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(ringback=${us-ring})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(transfer_ringback=local_stream://moh)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(call_timeout=35)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action set(hangup_after_bridge=true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action bridge(user/${dialed_extension}@${domain_name})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action sleep(1000)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [osbridge] ${nacf_target}() =~ /^\d{3,7}$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [osbridge] ${nacf_target}() =~ /^\d{10,15}$/ break=never
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Absolute Condition [osbridge]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action answer()
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action sleep(500)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action voicemail(default ${domain_name} ${dialed_extension})
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Local_Extension_VM] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Local_Extension_VM] destination_number(142) =~ /^vm-(\d{3,5})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->attented_xfer_to_vm] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [attented_xfer_to_vm] destination_number(142) =~ /^attented_xfer_to_vm$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->attented_xfer] continue=true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [attented_xfer] destination_number(142) =~ /^attented_xfer$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->blind_xfer_to] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [blind_xfer_to] destination_number(142) =~ /^blind_xfer_to$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Local_Extension] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Local_Extension] destination_number(142) =~ /^disable-(1[0-9][0-9][0-9]|[1-2][0-9][0-9])$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Local_Extension_Skinny] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Local_Extension_Skinny] destination_number(142) =~ /^(11[01][0-9])$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->park-in] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [park-in] destination_number(142) =~ /^call_park$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->park-out] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [park-out] destination_number(142) =~ /^\*(85\d\d)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->vmain] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [vmain] destination_number(142) =~ /^vmain$|^\*98$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->sip_uri] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [sip_uri] destination_number(142) =~ /^sip:(.*)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->local_uri] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [local_uri] destination_number(142) =~ /^(local_.*)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->nb_conferences] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [nb_conferences] destination_number(142) =~ /^\*(30\d{2,4})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->wb_conferences] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [wb_conferences] destination_number(142) =~ /^\*(31\d{2})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->uwb_conferences] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [uwb_conferences] destination_number(142) =~ /^\*(32\d{2})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->cdquality_conferences] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [cdquality_conferences] destination_number(142) =~ /^\*(33\d{2})$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->mad_boss_intercom] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [mad_boss_intercom] destination_number(142) =~ /^0911$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->mad_boss_intercom] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [mad_boss_intercom] destination_number(142) =~ /^0912$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->mad_boss] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [mad_boss] destination_number(142) =~ /^0913$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->queupark] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [queupark] destination_number(142) =~ /^\*(5902)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->queueunpark] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [queueunpark] destination_number(142) =~ /^\*(5903)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->park] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [park] destination_number(142) =~ /^\*5900$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->unpark] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [unpark] destination_number(142) =~ /^\*(5901)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->valet_park] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [valet_park] destination_number(142) =~ /^(6000)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->valet_park] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [valet_park] destination_number(142) =~ /^(60\d[1-9])$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->park] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [park] destination_number(142) =~ /park\+(\d+)/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->unpark] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [unpark] destination_number(142) =~ /^parking$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->park] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [park] destination_number(142) =~ /callpark/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->unpark] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [unpark] destination_number(142) =~ /pickup/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->wait] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [wait] destination_number(142) =~ /^wait$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->fax_receive] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [fax_receive] destination_number(142) =~ /^fax.*$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->fax_transmit] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [fax_transmit] destination_number(142) =~ /^9179$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->ringback_180] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [ringback_180] destination_number(142) =~ /^9180$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->ringback_183_uk_ring] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [ringback_183_uk_ring] destination_number(142) =~ /^\*9181$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->ringback_183_music_ring] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [ringback_183_music_ring] destination_number(142) =~ /^\*9182$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->ringback_post_answer_uk_ring] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(142) =~ /^\*9183$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->ringback_post_answer_music] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [ringback_post_answer_music] destination_number(142) =~ /^\*9184$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->show_info] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [show_info] destination_number(142) =~ /^\*9192$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->video_record] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [video_record] destination_number(142) =~ /^\*9193$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->video_playback] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [video_playback] destination_number(142) =~ /^\*9194$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->delay_echo] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [delay_echo] destination_number(142) =~ /^\*9195$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->echo] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [echo] destination_number(142) =~ /^\*9196$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->milliwatt] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [milliwatt] destination_number(142) =~ /^\*9197$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->tone_stream] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [tone_stream] destination_number(142) =~ /^\*9198$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->zrtp_enrollement] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [zrtp_enrollement] destination_number(142) =~ /^\*9787$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->hold_music] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [hold_music] destination_number(142) =~ /^\*9664$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->laugh break] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [laugh break] destination_number(142) =~ /^\*9386$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->101] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [101] destination_number(142) =~ /^101$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->pizza_demo] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [pizza_demo] destination_number(142) =~ /^(pizza|74992)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Talking Clock Time] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Talking Clock Time] destination_number(142) =~ /^9170$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Talking Clock Date] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Talking Clock Date] destination_number(142) =~ /^9171$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->Talking Clock Date and Time] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [Talking Clock Date and Time] destination_number(142) =~ /^9172$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->local.example.com] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [local.example.com] ${toll_allow}() =~ /local/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->domestic.example.com] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [domestic.example.com] ${toll_allow}() =~ /domestic/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->international.example.com] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [international.example.com] ${toll_allow}() =~ /international/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->conference] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [conference] destination_number(142) =~ /^112233$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->didforsale_ivr] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [didforsale_ivr] destination_number(142) =~ /5566/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->domian_ivr] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (FAIL) [domian_ivr] destination_number(142) =~ /ivr(1949.*)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com parsing [default->enum] continue=false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [enum] ${module_exists(mod_enum)}(true) =~ /true/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Regex (PASS) [enum] destination_number(142) =~ /^(.*)$/ break=on-false
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 Dialplan: sofia/internal/1276@domain.example.com Action transfer(142 enum)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1276@domain.example.com) State Change CS_ROUTING -> CS_EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1276@domain.example.com) State ROUTING going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1276@domain.example.com) State EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_sofia.c:196 sofia/internal/1276@domain.example.com SOFIA EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1276@domain.example.com Standard EXECUTE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com hash(insert/domain.example.com-spymap/1276/a17a5d15-d97e-4c70-b476-bd0ff4ac2e09)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com hash(insert/domain.example.com-last_dial/1276/142)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com hash(insert/domain.example.com-last_dial/global/a17a5d15-d97e-4c70-b476-bd0ff4ac2e09)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(RFC2822_DATE=Sat, 13 Feb 2016 09:23:10 -0800)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [RFC2822_DATE]=[Sat, 13 Feb 2016 09:23:10 -0800]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(proxy_media=false)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [proxy_media]=[false]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(continue_on_fail=true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [continue_on_fail]=[true]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(domain_name=domain.example.com)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [domain_name]=[domain.example.com]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.417792 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
2016-02-13 09:23:10.427795 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:23:10.427795 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(accountcode=17)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [accountcode]=[17]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com log(Extensions 142 is true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] mod_dptools.c:1692 142 is true
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bind_meta_app(1 b s execute_extension::dx XML features)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *1 execute_extension::dx XML features
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bind_meta_app(2 b s record_session::/usr/local/freeswitch/var/lib/freeswitch/recordings/domain.example.com/2016-02-13-09-23-10.a17a5d15-d97e-4c70-b476-bd0ff4ac2e09.wav)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *2 record_session::/usr/local/freeswitch/var/lib/freeswitch/recordings/domain.example.com/2016-02-13-09-23-10.a17a5d15-d97e-4c70-b476-bd0ff4ac2e09.wav
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bind_meta_app(3 b s execute_extension::cf XML features)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *3 execute_extension::cf XML features
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bind_meta_app(4 b s execute_extension::attented_xfer XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *4 execute_extension::attented_xfer XML default
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bind_meta_app(5 b s execute_extension::attented_xfer_to_vm XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *5 execute_extension::attented_xfer_to_vm XML default
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bind_meta_app(6 b s execute_extension::blind_xfer_to XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *6 execute_extension::blind_xfer_to XML default
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bind_meta_app(9 b s execute_extension::call_park XML default)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *9 execute_extension::call_park XML default
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com export(sip_contact_user=1276)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [sip_contact_user]=[1276]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(ringback=%(2000,4000,440,480))
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [ringback]=[%(2000,4000,440,480)]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(transfer_ringback=local_stream://moh)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [transfer_ringback]=[local_stream://moh]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(call_timeout=35)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [call_timeout]=[35]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com set(hangup_after_bridge=true)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1276@domain.example.com [hangup_after_bridge]=[true]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 EXECUTE sofia/internal/1276@domain.example.com bridge(user/142@domain.example.com)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [ALERT] switch_core_session.c:2781 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [caller-id-in-from]=[true] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [sip_cid_type]=[none] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [skip_cdr_causes]=[LOSE_RACE] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [accountcode]=[17] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [dialed_extension]=[142] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [available_to_call]=[true] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 13 Feb 2016 09:23:10 -0800] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [sip_contact_user]=[1276] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
2016-02-13 09:23:10.427795 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:23:10.427795 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [caller-id-in-from]=[true] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [sip_cid_type]=[none] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [skip_cdr_causes]=[LOSE_RACE] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [accountcode]=[17] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [dialed_extension]=[142] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [available_to_call]=[true] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 13 Feb 2016 09:23:10 -0800] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_channel.c:1247 sofia/internal/1276@domain.example.com EXPORTING[export_vars] [sip_contact_user]=[1276] to event
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.427795 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.427795 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/142@192.168.33.116:49383 [d9770cc8-1742-40cd-876f-3b5f50733449]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.427795 [DEBUG] mod_sofia.c:4776 (sofia/internal/142@192.168.33.116:49383) State Change CS_NEW -> CS_INIT
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@192.168.33.116:49383) Running State Change CS_INIT
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/142@192.168.33.116:49383) State INIT
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] mod_sofia.c:88 sofia/internal/142@192.168.33.116:49383 SOFIA INIT
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] sofia_glue.c:1228 sip:142@73.170.102.43:49383 Setting proxy route to sofia/internal/142@192.168.33.116:49383
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] sofia_glue.c:1257 sofia/internal/142@192.168.33.116:49383 sending invite version: 1.6.6  64bit
d9770cc8-1742-40cd-876f-3b5f50733449 Local SDP:
d9770cc8-1742-40cd-876f-3b5f50733449 v=0
d9770cc8-1742-40cd-876f-3b5f50733449 o=FreeSWITCH 1455355946 1455355947 IN IP4 219.206.20.22
d9770cc8-1742-40cd-876f-3b5f50733449 s=FreeSWITCH
d9770cc8-1742-40cd-876f-3b5f50733449 c=IN IP4 219.206.20.22
d9770cc8-1742-40cd-876f-3b5f50733449 t=0 0
d9770cc8-1742-40cd-876f-3b5f50733449 m=audio 28244 RTP/AVP 0 18 8 101 13
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:0 PCMU/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:18 G729/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:8 PCMA/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:101 telephone-event/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=fmtp:101 0-16
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:13 CN/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=ptime:20
d9770cc8-1742-40cd-876f-3b5f50733449 a=sendrecv
d9770cc8-1742-40cd-876f-3b5f50733449 
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:40 sofia/internal/142@192.168.33.116:49383 Standard INIT
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/142@192.168.33.116:49383) State Change CS_INIT -> CS_ROUTING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/142@192.168.33.116:49383) State INIT going to sleep
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@192.168.33.116:49383) Running State Change CS_ROUTING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] sofia.c:6760 Channel sofia/internal/142@192.168.33.116:49383 entering state [calling][0]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/142@192.168.33.116:49383) State ROUTING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] mod_sofia.c:141 sofia/internal/142@192.168.33.116:49383 SOFIA ROUTING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/142@192.168.33.116:49383) State Change CS_ROUTING -> CS_CONSUME_MEDIA
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/142@192.168.33.116:49383) State ROUTING going to sleep
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@192.168.33.116:49383) Running State Change CS_CONSUME_MEDIA
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/142@192.168.33.116:49383) State CONSUME_MEDIA
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/142@192.168.33.116:49383) State CONSUME_MEDIA going to sleep
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.437790 [ALERT] switch_core_state_machine.c:590 sofia/internal/142@192.168.33.116:49383 session thread sleep state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.507868 [ALERT] switch_core_state_machine.c:594 sofia/internal/142@192.168.33.116:49383 session thread wake state: CS_CONSUME_MEDIA!
2016-02-13 09:23:10.507868 [ALERT] sofia.c:1214 sofia/internal/142@192.168.33.116:49383 Same Callee ID "Outbound Call" <142>
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.507868 [DEBUG] sofia.c:6760 Channel sofia/internal/142@192.168.33.116:49383 entering state [proceeding][180]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.507868 [NOTICE] sofia.c:6862 Ring-Ready sofia/internal/142@192.168.33.116:49383!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.507868 [DEBUG] switch_channel.c:3340 (sofia/internal/142@192.168.33.116:49383) Callstate Change DOWN -> RINGING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.507868 [ALERT] sofia.c:6862 sofia/internal/142@192.168.33.116:49383 receive message [RING_EVENT]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:10.507868 [ALERT] switch_core_state_machine.c:590 sofia/internal/142@192.168.33.116:49383 session thread sleep state: CS_CONSUME_MEDIA!
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [ALERT] switch_ivr_originate.c:1216 sofia/internal/1276@domain.example.com receive message [PROGRESS]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [INFO] switch_ivr_originate.c:1216 Sending early media
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [ALERT] switch_core_media.c:376 Found peer channel; propagating zrtp-hash if set
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 96@8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/1276@domain.example.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_codec.c:111 sofia/internal/1276@domain.example.com Original read codec set to PCMU:0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 96@8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:4485 sofia/internal/1276@domain.example.com Set 2833 dtmf send payload to 96 recv payload to 96
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/1276@domain.example.com] 219.206.20.22 port 26876 -> 192.168.1.168 port 8002 codec: 0 ms: 20
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:1939 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:6332 sofia/internal/1276@domain.example.com Set 2833 dtmf send payload to 96
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:6339 sofia/internal/1276@domain.example.com Set 2833 dtmf receive payload to 96
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_media.c:6362 sofia/internal/1276@domain.example.com Set rtp dtmf delay to 60
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1276@domain.example.com!
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_channel.c:3468 (sofia/internal/1276@domain.example.com) Callstate Change RINGING -> EARLY
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [ALERT] sofia_media.c:92 sofia/internal/1276@domain.example.com receive message [PROGRESS_EVENT]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] mod_sofia.c:2330 Ring SDP:
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 v=0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 o=FreeSWITCH 1455357314 1455357315 IN IP4 219.206.20.22
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 s=FreeSWITCH
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 c=IN IP4 219.206.20.22
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 t=0 0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 m=audio 26876 RTP/AVP 0 96
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:0 PCMU/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:96 telephone-event/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=fmtp:96 0-16
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=ptime:20
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=sendrecv
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_ivr_originate.c:1274 Raw Codec Activation Success L16@8000hz 1 channel 20ms
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_core_codec.c:221 sofia/internal/1276@domain.example.com Push codec L16:100
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.517869 [DEBUG] switch_ivr_originate.c:1343 Play Ringback Tone [%(2000,4000,440,480)]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.537870 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@domain.example.com entering state [early][183]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.537870 [ALERT] switch_channel.c:3507 sofia/internal/1276@domain.example.com receive message [AUDIO_SYNC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.537870 [ALERT] switch_core_io.c:1135 sofia/internal/1276@domain.example.com receive message [TRANSCODING_NECESSARY]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:10.827958 [INFO] switch_rtp.c:6616 Auto Changing audio port from 192.168.1.168:8002 to 68.5.94.63:8002
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:13.517891 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57074 but expected: 57072 lost: 2
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.537897 [ALERT] switch_core_state_machine.c:594 sofia/internal/142@192.168.33.116:49383 session thread wake state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.537897 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.537897 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.537897 [ALERT] switch_core_media.c:376 Found peer channel; propagating zrtp-hash if set
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.537897 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.537897 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
2016-02-13 09:23:14.547896 [ALERT] sofia.c:1214 sofia/internal/142@192.168.33.116:49383 Same Callee ID "Outbound Call" <142>
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] sofia.c:6760 Channel sofia/internal/142@192.168.33.116:49383 entering state [completing][200]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] sofia.c:6770 Remote SDP:
d9770cc8-1742-40cd-876f-3b5f50733449 v=0
d9770cc8-1742-40cd-876f-3b5f50733449 o=142 8000 8000 IN IP4 192.168.33.116
d9770cc8-1742-40cd-876f-3b5f50733449 s=SIP Call
d9770cc8-1742-40cd-876f-3b5f50733449 c=IN IP4 192.168.33.116
d9770cc8-1742-40cd-876f-3b5f50733449 t=0 0
d9770cc8-1742-40cd-876f-3b5f50733449 m=audio 12552 RTP/AVP 0 8 101
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:0 PCMU/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:8 PCMA/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=rtpmap:101 telephone-event/8000
d9770cc8-1742-40cd-876f-3b5f50733449 a=fmtp:101 0-15
d9770cc8-1742-40cd-876f-3b5f50733449 a=ptime:20
d9770cc8-1742-40cd-876f-3b5f50733449 
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_state_machine.c:590 sofia/internal/142@192.168.33.116:49383 session thread sleep state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_state_machine.c:594 sofia/internal/142@192.168.33.116:49383 session thread wake state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] sofia.c:6760 Channel sofia/internal/142@192.168.33.116:49383 entering state [ready][200]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_media.c:376 Found peer channel; propagating zrtp-hash if set
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 101@8000
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/142@192.168.33.116:49383 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_codec.c:111 sofia/internal/142@192.168.33.116:49383 Original read codec set to PCMU:0
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 101@8000
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:4485 sofia/internal/142@192.168.33.116:49383 Set 2833 dtmf send payload to 101 recv payload to 101
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/142@192.168.33.116:49383] 219.206.20.22 port 28244 -> 192.168.33.116 port 12552 codec: 0 ms: 20
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:1939 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:6332 sofia/internal/142@192.168.33.116:49383 Set 2833 dtmf send payload to 101
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:6339 sofia/internal/142@192.168.33.116:49383 Set 2833 dtmf receive payload to 101
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_core_media.c:6362 sofia/internal/142@192.168.33.116:49383 Set rtp dtmf delay to 60
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [NOTICE] sofia.c:7724 Channel [sofia/internal/142@192.168.33.116:49383] has been answered
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [DEBUG] switch_channel.c:3767 (sofia/internal/142@192.168.33.116:49383) Callstate Change RINGING -> ACTIVE
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] sofia.c:7724 sofia/internal/142@192.168.33.116:49383 receive message [ANSWER_EVENT]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.547896 [ALERT] switch_core_state_machine.c:590 sofia/internal/142@192.168.33.116:49383 session thread sleep state: CS_CONSUME_MEDIA!
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [DEBUG] switch_core_codec.c:246 sofia/internal/1276@domain.example.com Restore previous codec PCMU:0.
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_originate.c:3550 sofia/internal/1276@domain.example.com receive message [ANSWER]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [DEBUG] mod_sofia.c:799 Local SDP sofia/internal/1276@domain.example.com:
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 v=0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 o=FreeSWITCH 1455357314 1455357316 IN IP4 219.206.20.22
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 s=FreeSWITCH
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 c=IN IP4 219.206.20.22
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 t=0 0
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 m=audio 26876 RTP/AVP 0 96
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:0 PCMU/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=rtpmap:96 telephone-event/8000
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=fmtp:96 0-16
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=ptime:20
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 a=sendrecv
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [NOTICE] switch_ivr_originate.c:3550 Channel [sofia/internal/1276@domain.example.com] has been answered
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [DEBUG] switch_channel.c:3767 (sofia/internal/1276@domain.example.com) Callstate Change EARLY -> ACTIVE
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_originate.c:3550 sofia/internal/1276@domain.example.com receive message [ANSWER_EVENT]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@domain.example.com entering state [completed][200]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/142@192.168.33.116:49383]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_originate.c:3944 sofia/internal/1276@domain.example.com receive message [AUDIO_SYNC]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_core_state_machine.c:594 sofia/internal/142@192.168.33.116:49383 session thread wake state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_originate.c:3941 sofia/internal/142@192.168.33.116:49383 receive message [AUDIO_SYNC]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_core_state_machine.c:590 sofia/internal/142@192.168.33.116:49383 session thread sleep state: CS_CONSUME_MEDIA!
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/142@192.168.33.116:49383]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_originate.c:3944 sofia/internal/1276@domain.example.com receive message [AUDIO_SYNC]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_core_state_machine.c:594 sofia/internal/142@192.168.33.116:49383 session thread wake state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_originate.c:3941 sofia/internal/142@192.168.33.116:49383 receive message [AUDIO_SYNC]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_core_state_machine.c:590 sofia/internal/142@192.168.33.116:49383 session thread sleep state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_core_state_machine.c:594 sofia/internal/142@192.168.33.116:49383 session thread wake state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_bridge.c:1452 sofia/internal/142@192.168.33.116:49383 receive message [AUDIO_SYNC]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_core_state_machine.c:590 sofia/internal/142@192.168.33.116:49383 session thread sleep state: CS_CONSUME_MEDIA!
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [ALERT] switch_ivr.c:217 sofia/internal/1276@domain.example.com receive message [AUDIO_SYNC]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_bridge.c:1451 sofia/internal/1276@domain.example.com receive message [AUDIO_SYNC]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_bridge.c:1551 sofia/internal/142@192.168.33.116:49383 receive message [BRIDGE]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [DEBUG] switch_core_media.c:9156 sofia/internal/142@192.168.33.116:49383 PAUSE Jitterbuffer
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [ALERT] switch_ivr_bridge.c:1559 sofia/internal/1276@domain.example.com receive message [BRIDGE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.557902 [DEBUG] switch_core_media.c:9156 sofia/internal/1276@domain.example.com PAUSE Jitterbuffer
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [DEBUG] switch_ivr_bridge.c:1591 (sofia/internal/142@192.168.33.116:49383) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [ALERT] switch_core_state_machine.c:594 sofia/internal/142@192.168.33.116:49383 session thread wake state: CS_CONSUME_MEDIA!
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@192.168.33.116:49383) Running State Change CS_EXCHANGE_MEDIA
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/142@192.168.33.116:49383) State EXCHANGE_MEDIA
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.557902 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.577825 [ALERT] switch_core_io.c:420 sofia/internal/1276@domain.example.com receive message [TRANSCODING_NECESSARY]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.657897 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@domain.example.com entering state [ready][200]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:14.667847 [ALERT] switch_ivr_bridge.c:283 sofia/internal/142@192.168.33.116:49383 receive message [DISPLAY]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.677907 [ALERT] switch_ivr_bridge.c:283 sofia/internal/1276@domain.example.com receive message [DISPLAY]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:14.697815 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:15.367824 [INFO] switch_rtp.c:6616 Auto Changing audio port from 192.168.33.116:12552 to 73.170.102.43:12552
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:15.507826 [ALERT] switch_rtp.c:1612 sofia/internal/142@192.168.33.116:49383 Got: audio seq 17 but expected: 16 lost: 1
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:15.617825 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57095 but expected: 57093 lost: 2
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:16.437897 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57116 but expected: 57115 lost: 1
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:17.747957 [ALERT] switch_rtp.c:1612 sofia/internal/142@192.168.33.116:49383 Got: audio seq 129 but expected: 128 lost: 1
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:18.987891 [ALERT] switch_rtp.c:1612 sofia/internal/142@192.168.33.116:49383 Got: audio seq 191 but expected: 190 lost: 1
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:19.307915 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57160 but expected: 57155 lost: 5
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:20.797895 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57194 but expected: 57192 lost: 2
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:21.247947 [ALERT] switch_rtp.c:1612 sofia/internal/142@192.168.33.116:49383 Got: audio seq 304 but expected: 303 lost: 1
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:22.467916 [ALERT] switch_rtp.c:1612 sofia/internal/142@192.168.33.116:49383 Got: audio seq 365 but expected: 364 lost: 1
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:22.617927 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57247 but expected: 57245 lost: 2
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:23.477916 [ALERT] switch_rtp.c:1612 sofia/internal/142@192.168.33.116:49383 Got: audio seq 416 but expected: 415 lost: 1
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:23.897894 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57297 but expected: 57296 lost: 1
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:24.757895 [ALERT] switch_rtp.c:1612 sofia/internal/1276@domain.example.com Got: audio seq 57315 but expected: 57313 lost: 2
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:25.747894 [ALERT] switch_rtp.c:1612 sofia/internal/142@192.168.33.116:49383 Got: audio seq 529 but expected: 528 lost: 1
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/+12127773456@66.62.60.228:5060 [022e5d6e-83a2-4397-84ba-f0f49aa6eef6]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/+12127773456@66.62.60.228:5060) Running State Change CS_NEW
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] sofia.c:9248 sofia/internal/+12127773456@66.62.60.228:5060 receiving invite from 209.216.2.222:5660 version: 1.6.6  64bit
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_media.c:370 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
2016-02-13 09:23:25.807894 [DEBUG] sofia.c:9360 IP 209.216.2.222 Approved by acl "domains[]". Access Granted.
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] sofia.c:6760 Channel sofia/internal/+12127773456@66.62.60.228:5060 entering state [received][100]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] sofia.c:6770 Remote SDP:
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 v=0
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 o=Sonus_UAC 28164 6556 IN IP4 66.62.60.228
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 s=SIP Media Capabilities
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 c=IN IP4 66.62.60.229
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 t=0 0
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 m=audio 11570 RTP/AVP 18 0 101
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=rtpmap:18 G729/8000
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=fmtp:18 annexb=no
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=rtpmap:0 PCMU/8000
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=rtpmap:101 telephone-event/8000
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=fmtp:101 0-15
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=maxptime:20
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] sofia.c:7125 (sofia/internal/+12127773456@66.62.60.228:5060) State Change CS_NEW -> CS_INIT
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/+12127773456@66.62.60.228:5060) State NEW
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/+12127773456@66.62.60.228:5060) Running State Change CS_INIT
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/+12127773456@66.62.60.228:5060) State INIT
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_sofia.c:88 sofia/internal/+12127773456@66.62.60.228:5060 SOFIA INIT
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:40 sofia/internal/+12127773456@66.62.60.228:5060 Standard INIT
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/+12127773456@66.62.60.228:5060) State Change CS_INIT -> CS_ROUTING
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/+12127773456@66.62.60.228:5060) State INIT going to sleep
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/+12127773456@66.62.60.228:5060) Running State Change CS_ROUTING
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_channel.c:2247 (sofia/internal/+12127773456@66.62.60.228:5060) Callstate Change DOWN -> RINGING
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/+12127773456@66.62.60.228:5060) State ROUTING
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_sofia.c:141 sofia/internal/+12127773456@66.62.60.228:5060 SOFIA ROUTING
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:166 sofia/internal/+12127773456@66.62.60.228:5060 Standard ROUTING
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [INFO] mod_dialplan_xml.c:637 Processing FOOD BANK NYC <+12127773456>->19499300360 in context public
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 parsing [public->limit_exceeded] continue=false
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Regex (FAIL) [limit_exceeded] destination_number(19499300360) =~ /^limit_exceeded$/ break=on-false
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 parsing [public->from_testopensip] continue=false
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Regex (FAIL) [from_testopensip] network_addr(209.216.2.222) =~ /^209\.216\.15\.18$/ break=on-false
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 parsing [public->from_productionopensip] continue=false
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Regex (PASS) [from_productionopensip] network_addr(209.216.2.222) =~ /^209\.216\.2\.222$/ break=on-false
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Action set(dialed_domain=${sip_to_host})
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Action set(domain_name=${sip_to_host})
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Action set(company_name=${sip_to_host})
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Action export(caller-id-in-from=true)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Action export(sip_cid_type=none)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Action lua(didhandle.lua ${destination_number})
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 Dialplan: sofia/internal/+12127773456@66.62.60.228:5060 Action log(INFO public Local URI var_name chan var is ${domain_name} Dialed domain is ${dialed_domain})
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/+12127773456@66.62.60.228:5060) State Change CS_ROUTING -> CS_EXECUTE
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/+12127773456@66.62.60.228:5060) State ROUTING going to sleep
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/+12127773456@66.62.60.228:5060) Running State Change CS_EXECUTE
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/+12127773456@66.62.60.228:5060) State EXECUTE
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_sofia.c:196 sofia/internal/+12127773456@66.62.60.228:5060 SOFIA EXECUTE
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_core_state_machine.c:258 sofia/internal/+12127773456@66.62.60.228:5060 Standard EXECUTE
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 set(dialed_domain=209.216.15.70)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_dptools.c:1498 SET sofia/internal/+12127773456@66.62.60.228:5060 [dialed_domain]=[209.216.15.70]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 set(domain_name=209.216.15.70)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_dptools.c:1498 SET sofia/internal/+12127773456@66.62.60.228:5060 [domain_name]=[209.216.15.70]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 set(company_name=209.216.15.70)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_dptools.c:1498 SET sofia/internal/+12127773456@66.62.60.228:5060 [company_name]=[209.216.15.70]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(caller-id-in-from=true)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [caller-id-in-from]=[true]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(sip_cid_type=none)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [sip_cid_type]=[none]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 lua(didhandle.lua 19499300360)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
2016-02-13 09:23:25.807894 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(domain_name=209.216.15.70)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [domain_name]=[209.216.15.70]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(skip_cdr_causes=LOSE_RACE)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [skip_cdr_causes]=[LOSE_RACE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [INFO] switch_cpp.cpp:1284 RPID is <sip:+12127773456@66.62.60.228:5060>;privacy=off dialing number is +12127773456
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 limit(hash +12127773456 0 1/10)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_limit.c:126 incr called: +12127773456_0 max:1, interval:10
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_hash.c:198 Usage for +12127773456_0 is now 1/1 for the last 10 seconds
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 limit(hash +12127773456 0 3/60)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] switch_limit.c:126 incr called: +12127773456_0 max:3, interval:60
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 set(effective_caller_id_name=FOOD BANK NYC)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [DEBUG] mod_dptools.c:1498 SET sofia/internal/+12127773456@66.62.60.228:5060 [effective_caller_id_name]=[FOOD BANK NYC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.807894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
2016-02-13 09:23:25.817894 [NOTICE] switch_cpp.cpp:1356 Product List Query SQL: select cebod_card.hold_music,cebod_card.status, cp.iduser, cp.product, cp.producttype as producttype, cp.activated, cd.domain, lower(cp.destinationtype) as destinationtype, cp.destination, cp.timecondition, cp.timeid, cp.timedestinationtype, cp.timedestination, cd.pbxip as pbxip, recording, whisper, whisper_file, postcall_id, substring(grp_description,1,15) as grpdescription  from cebod_products as cp, domain as cd left join cebod_card on cebod_card.id=iduser where  cp.iduser=cd.iduser and substr(cp.product,length(cp.product)-9,10) = substr('19499300360',length('19499300360')-9,10) 
2016-02-13 09:23:25.817894 [NOTICE] switch_cpp.cpp:1356 Time Availability : yes
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 set(api_hangup_hook=lua postcall.lua 1 domain.example.com)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] mod_dptools.c:1498 SET sofia/internal/+12127773456@66.62.60.228:5060 [api_hangup_hook]=[lua postcall.lua 1 domain.example.com]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [INFO] switch_cpp.cpp:1284 Check id called id is blocked or not ,  notblockedSQL is select * from cebod_callblock where domain=domain.example.com and ( upper(phonenumber)=upper(substr(12127773456,1,length(phonenumber))) or upper(phonenumber)=upper(substr(FOOD BANK NYC,1,length(phonenumber))) ) limit 1
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [INFO] switch_cpp.cpp:1284 Check id called id+12127773456 is blocked or not ,  notblocked
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(calltype=origination)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [calltype]=[origination]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 limit(hash +12127773456 17 1/10)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] switch_limit.c:126 incr called: +12127773456_17 max:1, interval:10
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] mod_hash.c:198 Usage for +12127773456_17 is now 1/1 for the last 10 seconds
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(domain_name=domain.example.com)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [domain_name]=[domain.example.com]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(accountcode=17)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [accountcode]=[17]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 export(setinboundcalllimit=setlimitdomain.example.com 17 15)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [setinboundcalllimit]=[setlimitdomain.example.com 17 15]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 limit(hash domain.example.com 17 15)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] switch_limit.c:126 incr called: domain.example.com_17 max:15, interval:0
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [DEBUG] mod_hash.c:196 Usage for domain.example.com_17 is now 1/15
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:25.817894 [ALERT] switch_core_session.c:2796 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.327895 [NOTICE] sofia.c:952 Hangup sofia/internal/1276@domain.example.com [CS_EXECUTE] [NORMAL_CLEARING]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_ivr_bridge.c:699 sofia/internal/1276@domain.example.com ending bridge by request from write function
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/142@192.168.33.116:49383]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [NOTICE] switch_ivr_bridge.c:881 Hangup sofia/internal/142@192.168.33.116:49383 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/142@192.168.33.116:49383) State EXCHANGE_MEDIA going to sleep
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@192.168.33.116:49383) Running State Change CS_HANGUP
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/142@192.168.33.116:49383) Callstate Change ACTIVE -> HANGUP
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/142@192.168.33.116:49383) State HANGUP
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] mod_sofia.c:425 sofia/internal/142@192.168.33.116:49383 Overriding SIP cause 480 with 200 from the other leg
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] mod_sofia.c:431 Channel sofia/internal/142@192.168.33.116:49383 hanging up, cause: NORMAL_CLEARING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] mod_sofia.c:484 Sending BYE to sofia/internal/142@192.168.33.116:49383
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:60 sofia/internal/142@192.168.33.116:49383 Standard HANGUP, cause: NORMAL_CLEARING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/142@192.168.33.116:49383) State HANGUP going to sleep
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/142@192.168.33.116:49383) State Change CS_HANGUP -> CS_REPORTING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@192.168.33.116:49383) Running State Change CS_REPORTING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/142@192.168.33.116:49383) State REPORTING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:104 sofia/internal/142@192.168.33.116:49383 Standard REPORTING, cause: NORMAL_CLEARING
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/142@192.168.33.116:49383) State REPORTING going to sleep
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/142@192.168.33.116:49383) State Change CS_REPORTING -> CS_DESTROY
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.327895 [DEBUG] switch_core_session.c:1646 Session 1857 (sofia/internal/142@192.168.33.116:49383) Locked, Waiting on external entities
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_ivr_bridge.c:705 sofia/internal/1276@domain.example.com ending bridge by request from read function
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1276@domain.example.com]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [ALERT] switch_ivr_bridge.c:1692 sofia/internal/1276@domain.example.com receive message [UNBRIDGE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_ivr_bridge.c:1692 sofia/internal/1276@domain.example.com skip receive message [UNBRIDGE] (channel is hungup already)
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.337895 [NOTICE] switch_core_session.c:1664 Session 1857 (sofia/internal/142@192.168.33.116:49383) Ended
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.337895 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/142@192.168.33.116:49383 [CS_DESTROY]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [ALERT] switch_core_session.c:2796 sofia/internal/1276@domain.example.com receive message [APPLICATION_EXEC_COMPLETE]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_session.c:2796 sofia/internal/1276@domain.example.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1276@domain.example.com) State EXECUTE going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_HANGUP
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/142@192.168.33.116:49383) Running State Change CS_DESTROY
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/142@192.168.33.116:49383) State DESTROY
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.337895 [DEBUG] mod_sofia.c:341 sofia/internal/142@192.168.33.116:49383 SOFIA DESTROY
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:111 sofia/internal/142@192.168.33.116:49383 Standard DESTROY
d9770cc8-1742-40cd-876f-3b5f50733449 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/142@192.168.33.116:49383) State DESTROY going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1276@domain.example.com) Callstate Change ACTIVE -> HANGUP
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1276@domain.example.com) State HANGUP
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1276@domain.example.com hanging up, cause: NORMAL_CLEARING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1276@domain.example.com Standard HANGUP, cause: NORMAL_CLEARING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1276@domain.example.com) State HANGUP going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1276@domain.example.com) State Change CS_HANGUP -> CS_REPORTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@domain.example.com) Running State Change CS_REPORTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1276@domain.example.com) State REPORTING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.337895 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cebod_cdr (used_gateway_id, phonenumber, call_uuid, leg, job_uuid, callrequest_id, accountcode, created_date, callerid, billsec, callername, starting_date, hangup_cause, duration, event_name) VALUES (68.5.94.63, 142, a17a5d15-d97e-4c70-b476-bd0ff4ac2e09, inbound, a17a5d15-d97e-4c70-b476-bd0ff4ac2e09, a17a5d15-d97e-4c70-b476-bd0ff4ac2e09, 17, 2016-02-13 09:23:26, 1276, 12, Kunal Mittal, 2016-02-13 09:23:10, NORMAL_CLEARING, 16, REQUEST_PARAMS)
2016-02-13 09:23:26.337895 [ALERT] switch_odbc.c:353 Connecting opensips
2016-02-13 09:23:26.337895 [ALERT] switch_odbc.c:397 Connected to [opensips]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1276@domain.example.com Standard REPORTING, cause: NORMAL_CLEARING
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1276@domain.example.com) State REPORTING going to sleep
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1276@domain.example.com) State Change CS_REPORTING -> CS_DESTROY
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_session.c:1646 Session 1856 (sofia/internal/1276@domain.example.com) Locked, Waiting on external entities
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [NOTICE] switch_core_session.c:1664 Session 1856 (sofia/internal/1276@domain.example.com) Ended
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1276@domain.example.com [CS_DESTROY]
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1276@domain.example.com) Running State Change CS_DESTROY
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1276@domain.example.com) State DESTROY
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] mod_sofia.c:341 sofia/internal/1276@domain.example.com SOFIA DESTROY
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1276@domain.example.com Standard DESTROY
a17a5d15-d97e-4c70-b476-bd0ff4ac2e09 2016-02-13 09:23:26.347896 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1276@domain.example.com) State DESTROY going to sleep
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [INFO] switch_cpp.cpp:1284 Domain name is : 219.206.20.22dialed number 19499300360 destiantiotype is ivr destination is 36 account activation status: 1
2016-02-13 09:23:26.827933 [NOTICE] switch_cpp.cpp:1356 Get Special Rate inforamtion: select billingblock, per_min_did as rate from cebod_specialrate where id_cc_card in (0,17) order by id_cc_card desc limit 1
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [INFO] switch_cpp.cpp:1284 Sending Call to  IVR, IVR ID is 36
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 EXECUTE sofia/internal/+12127773456@66.62.60.228:5060 lua(ivr_menu.lua 36 domain.example.com)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [ALERT] switch_core_session.c:2781 sofia/internal/+12127773456@66.62.60.228:5060 receive message [APPLICATION_EXEC]
2016-02-13 09:23:26.827933 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f4164033540 Connected.
2016-02-13 09:23:26.827933 [NOTICE] switch_cpp.cpp:1356 [ivr_menu] SQL: SELECT * FROM cebod_ivr_menus
                WHERE ivr_id = '36'
                AND ivr_menu_enabled = 'true' 
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [ALERT] switch_cpp.cpp:683 sofia/internal/+12127773456@66.62.60.228:5060 receive message [ANSWER]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [ALERT] switch_core_media.c:373 No partner channel found, so not propagating zrtp-hash
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 101@8000
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/+12127773456@66.62.60.228:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_codec.c:111 sofia/internal/+12127773456@66.62.60.228:5060 Original read codec set to G729:18
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 101@8000
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:4485 sofia/internal/+12127773456@66.62.60.228:5060 Set 2833 dtmf send payload to 101 recv payload to 101
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/+12127773456@66.62.60.228:5060] 219.206.20.22 port 28274 -> 66.62.60.229 port 11570 codec: 18 ms: 20
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:1939 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:6332 sofia/internal/+12127773456@66.62.60.228:5060 Set 2833 dtmf send payload to 101
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:6339 sofia/internal/+12127773456@66.62.60.228:5060 Set 2833 dtmf receive payload to 101
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_core_media.c:6362 sofia/internal/+12127773456@66.62.60.228:5060 Set rtp dtmf delay to 60
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/+12127773456@66.62.60.228:5060!
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_channel.c:3468 (sofia/internal/+12127773456@66.62.60.228:5060) Callstate Change RINGING -> EARLY
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [ALERT] sofia_media.c:92 sofia/internal/+12127773456@66.62.60.228:5060 receive message [PROGRESS_EVENT]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] mod_sofia.c:799 Local SDP sofia/internal/+12127773456@66.62.60.228:5060:
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 v=0
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 o=FreeSWITCH 1455355932 1455355933 IN IP4 219.206.20.22
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 s=FreeSWITCH
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 c=IN IP4 219.206.20.22
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 t=0 0
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 m=audio 28274 RTP/AVP 18 101
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=rtpmap:18 G729/8000
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=fmtp:18 annexb=no
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=rtpmap:101 telephone-event/8000
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=fmtp:101 0-16
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=ptime:20
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 a=sendrecv
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [NOTICE] switch_cpp.cpp:683 Channel [sofia/internal/+12127773456@66.62.60.228:5060] has been answered
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] switch_channel.c:3767 (sofia/internal/+12127773456@66.62.60.228:5060) Callstate Change EARLY -> ACTIVE
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [ALERT] switch_cpp.cpp:683 sofia/internal/+12127773456@66.62.60.228:5060 receive message [ANSWER_EVENT]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.827933 [DEBUG] sofia.c:6760 Channel sofia/internal/+12127773456@66.62.60.228:5060 entering state [completed][200]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.887900 [DEBUG] sofia.c:6760 Channel sofia/internal/+12127773456@66.62.60.228:5060 entering state [ready][200]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:26.957827 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.

022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:27.837896 [DEBUG] switch_ivr_play_say.c:1359 Codec Activated L16@8000hz 1 channels 20ms
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:27.837896 [ALERT] switch_ivr_play_say.c:1290 sofia/internal/+12127773456@66.62.60.228:5060 receive message [AUDIO_SYNC]
022e5d6e-83a2-4397-84ba-f0f49aa6eef6 2016-02-13 09:23:27.857913 [ALERT] switch_core_io.c:1135 sofia/internal/+12127773456@66.62.60.228:5060 receive message [TRANSCODING_NECESSARY]
2016-02-13 09:23:27.857913 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7f418400f440 0x7f418400f440
2016-02-13 09:23:27.857913 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7f418400f440 0x7f418400f440
2016-02-13 09:23:29.137959 [ALERT] switch_odbc.c:353 Connecting opensips
2016-02-13 09:23:29.137959 [ALERT] switch_odbc.c:397 Connected to [opensips]
2016-02-13 09:23:29.137959 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f424c24f000 Connected.
2016-02-13 09:23:29.157902 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f424c24f000 released.
2016-02-13 09:23:32.567895 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f424c24f000 Connected.
2016-02-13 09:23:32.567895 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f424c24f000 released.
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/124.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/164.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/638.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/474.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/290.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/128.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/608.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/428.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/374.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/17.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/236.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/322.csv
2016-02-13 09:23:34.197900 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/var/log/freeswitch/cdr-csv/432.csv