2016-02-13 09:21:50.767867 [NOTICE] mod_logfile.c:213 New log started.
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.607889 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/142@domain.example.com:5660 [03d4582f-ed06-4268-a70b-0dd9307fb4cf]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.607889 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_NEW
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.607889 [DEBUG] sofia.c:9248 sofia/internal/142@domain.example.com:5660 receiving invite from 73.170.102.43:49383 version: 1.6.6  64bit
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.607889 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.607889 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.607889 [ALERT] switch_core_media.c:370 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
2016-02-13 09:21:55.607889 [DEBUG] sofia.c:9415 IP 73.170.102.43 Rejected by acl "domains". Falling back to Digest auth.
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.607889 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/142@domain.example.com:5660) State NEW
2016-02-13 09:21:55.607889 [DEBUG] sofia.c:2147 detaching session 03d4582f-ed06-4268-a70b-0dd9307fb4cf
2016-02-13 09:21:55.637867 [DEBUG] sofia.c:2255 Re-attaching to session 03d4582f-ed06-4268-a70b-0dd9307fb4cf
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.647889 [DEBUG] sofia.c:9248 sofia/internal/142@domain.example.com:5660 receiving invite from 73.170.102.43:49383 version: 1.6.6  64bit
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.647889 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.647889 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.647889 [ALERT] switch_core_media.c:370 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
2016-02-13 09:21:55.647889 [DEBUG] sofia.c:9415 IP 73.170.102.43 Rejected by acl "domains". Falling back to Digest auth.
2016-02-13 09:21:55.647889 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:21:55.657791 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] sofia.c:10549 Setting NAT mode based on nat.auto
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] sofia.c:6760 Channel sofia/internal/142@domain.example.com:5660 entering state [received][100]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] sofia.c:6770 Remote SDP:
03d4582f-ed06-4268-a70b-0dd9307fb4cf v=0
03d4582f-ed06-4268-a70b-0dd9307fb4cf o=142 8000 8000 IN IP4 192.168.33.116
03d4582f-ed06-4268-a70b-0dd9307fb4cf s=SIP Call
03d4582f-ed06-4268-a70b-0dd9307fb4cf c=IN IP4 192.168.33.116
03d4582f-ed06-4268-a70b-0dd9307fb4cf t=0 0
03d4582f-ed06-4268-a70b-0dd9307fb4cf m=audio 50028 RTP/AVP 0 8 101
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:0 PCMU/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:8 PCMA/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:101 telephone-event/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=fmtp:101 0-15
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=ptime:20
03d4582f-ed06-4268-a70b-0dd9307fb4cf 
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] sofia.c:7125 (sofia/internal/142@domain.example.com:5660) State Change CS_NEW -> CS_INIT
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_INIT
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/142@domain.example.com:5660) State INIT
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_sofia.c:88 sofia/internal/142@domain.example.com:5660 SOFIA INIT
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:40 sofia/internal/142@domain.example.com:5660 Standard INIT
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/142@domain.example.com:5660) State Change CS_INIT -> CS_ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/142@domain.example.com:5660) State INIT going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_channel.c:2247 (sofia/internal/142@domain.example.com:5660) Callstate Change DOWN -> RINGING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/142@domain.example.com:5660) State ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_sofia.c:141 sofia/internal/142@domain.example.com:5660 SOFIA ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:166 sofia/internal/142@domain.example.com:5660 Standard ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [INFO] mod_dialplan_xml.c:637 Processing 142 <142>->1276 in context public
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [public->limit_exceeded] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [limit_exceeded] destination_number(1276) =~ /^limit_exceeded$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [public->from_testopensip] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [from_testopensip] network_addr(73.170.102.43) =~ /^209\.216\.15\.18$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [public->from_productionopensip] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [from_productionopensip] network_addr(73.170.102.43) =~ /^209\.216\.2\.222$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [public->from_registeredusers] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Absolute Condition [from_registeredusers]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(dialed_domain=${sip_to_host})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(domain_name=${sip_to_host})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(company_name=${sip_to_host})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action export(caller-id-in-from=true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action export(sip_cid_type=none)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action log(Public IP is ${bind_server_ip} Domain is ${domain_name} Dialed domain is ${dialed_domain})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action lua(didhandle.lua ${destination_number})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action log(INFO public Local URI var_name chan var is ${domain_name} Dialed domain is ${dialed_domain})
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/142@domain.example.com:5660) State Change CS_ROUTING -> CS_EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/142@domain.example.com:5660) State ROUTING going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/142@domain.example.com:5660) State EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_sofia.c:196 sofia/internal/142@domain.example.com:5660 SOFIA EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:258 sofia/internal/142@domain.example.com:5660 Standard EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(dialed_domain=domain.example.com)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [dialed_domain]=[domain.example.com]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(domain_name=domain.example.com)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [domain_name]=[domain.example.com]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(company_name=domain.example.com)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [company_name]=[domain.example.com]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(caller-id-in-from=true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [caller-id-in-from]=[true]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(sip_cid_type=none)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [sip_cid_type]=[none]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 log(Public IP is 219.206.20.22 Domain is domain.example.com Dialed domain is domain.example.com)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_dptools.c:1692 IP is 219.206.20.22 Domain is domain.example.com Dialed domain is domain.example.com
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 lua(didhandle.lua 1276)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
2016-02-13 09:21:55.657791 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(domain_name=domain.example.com)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [domain_name]=[domain.example.com]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(skip_cdr_causes=LOSE_RACE)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [skip_cdr_causes]=[LOSE_RACE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [INFO] switch_cpp.cpp:1284 RPID is  dialing number is 142
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(accountcode=17)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [accountcode]=[17]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [INFO] switch_cpp.cpp:1284 String length is less that 7 should be extension
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(process_cdr=a_only)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [process_cdr]=[a_only]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_ivr.c:2085 (sofia/internal/142@domain.example.com:5660) State Change CS_EXECUTE -> CS_ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_ivr.c:2090 sofia/internal/142@domain.example.com:5660 receive message [TRANSFER]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [NOTICE] switch_ivr.c:2092 Transfer sofia/internal/142@domain.example.com:5660 to XML[1276@default]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_cpp.cpp:898 transfer result: 0
2016-02-13 09:21:55.657791 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_cpp.cpp:1103 sofia/internal/142@domain.example.com:5660 destroy/unlink session from object
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/142@domain.example.com:5660) State EXECUTE going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/142@domain.example.com:5660) State ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_sofia.c:141 sofia/internal/142@domain.example.com:5660 SOFIA ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] switch_core_state_machine.c:166 sofia/internal/142@domain.example.com:5660 Standard ROUTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [INFO] mod_dialplan_xml.c:637 Processing 142 <142>->1276 in context default
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->longdistance] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [longdistance] destination_number(1276) =~ /^\d{10,15}$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->unloop] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->freeswitch_public_conf_via_sip] continue=false
2016-02-13 09:21:55.657791 [ERR] switch_regex.c:104 COMPILE ERROR: 1 [nothing to repeat][^*9(888|8888|1616|3232)$]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(1276) =~ /^*9(888|8888|1616|3232)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->tod_example] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Date/TimeMatch (FAIL) [tod_example] break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->holiday_example] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Date/TimeMatch (FAIL) [holiday_example] break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->global-intercept] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [global-intercept] destination_number(1276) =~ /^\*886$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->group-intercept] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [group-intercept] destination_number(1276) =~ /^\*8$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->intercept-ext] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [intercept-ext] destination_number(1276) =~ /^\*\*(\d+)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->redial] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [redial] destination_number(1276) =~ /^\*(redial|870)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->global] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [global] ${switch_r_sdp}(v=0
03d4582f-ed06-4268-a70b-0dd9307fb4cf o=142 8000 8000 IN IP4 192.168.33.116
03d4582f-ed06-4268-a70b-0dd9307fb4cf s=SIP Call
03d4582f-ed06-4268-a70b-0dd9307fb4cf c=IN IP4 192.168.33.116
03d4582f-ed06-4268-a70b-0dd9307fb4cf t=0 0
03d4582f-ed06-4268-a70b-0dd9307fb4cf m=audio 50028 RTP/AVP 0 8 101
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:0 PCMU/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:8 PCMA/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:101 telephone-event/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=fmtp:101 0-15
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=ptime:20
03d4582f-ed06-4268-a70b-0dd9307fb4cf ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Absolute Condition [global]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action hash(insert/${domain_name}-last_dial/global/${uuid})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->snom-demo-2] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [snom-demo-2] destination_number(1276) =~ /^9001$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->snom-demo-1] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [snom-demo-1] destination_number(1276) =~ /^9000$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->eavesdrop] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [eavesdrop] destination_number(1276) =~ /^\*88(\d{3,5})$|^\*0(.*)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->eavesdrop] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [eavesdrop] destination_number(1276) =~ /^\*779$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->call_return] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [call_return] destination_number(1276) =~ /^\*69$|^\*869$|^lcr$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->del-number] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [del-number] destination_number(1276) =~ /^\*60$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->add-number] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [add-number] destination_number(1276) =~ /^\*61$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->check-number] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [check-number] destination_number(1276) =~ /^\*62$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->enable-time-condition] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [enable-time-condition] destination_number(1276) =~ /^\*63$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->disable-time-condition] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [disable-time-condition] destination_number(1276) =~ /^\*64$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->del-group] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [del-group] destination_number(1276) =~ /^\*80$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->add-group] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [add-group] destination_number(1276) =~ /^\*81$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->call-group-simo] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [call-group-simo] destination_number(1276) =~ /^\*82(\d{2})$|^82(\d{2})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->call-group-order] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [call-group-order] destination_number(1276) =~ /^\*83(\d{2})$|^83(\d{2})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->extension-intercom] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [extension-intercom] destination_number(1276) =~ /^\*84(\d{3,5})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->user_recodring_enabled] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [user_recodring_enabled] ${recording}(off) =~ /^(on|off)$/ break=on-true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->user_callscreening] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [user_callscreening] caller_id_number(142) =~ /^\d{10,15}$/ break=on-true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [user_callscreening] ${callscreening}(off) =~ /^(on|off)$/ break=on-true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Check IVR-based CF] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [Check IVR-based CF] destination_number(1276) =~ /^(\d+)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(dialed_number=1276) INLINE
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(dialed_number=1276)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [dialed_number]=[1276]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.657791 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(cf_target=${db(select/${domain_name}-CF/1276)}) INLINE
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(cf_target=)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [cf_target]=[UNDEF]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Check IVR-based CF] ${recording}(off) =~ /^on$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Check IVR-based CF] ${cf_target}() =~ /^\d{3,5}$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Check IVR-based CF] ${cf_target}() =~ /^\d{10,15}$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->CF from my station] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [CF from my station] destination_number(1276) =~ /^\*72$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->CF cancel from my station] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [CF cancel from my station] destination_number(1276) =~ /^\*73$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->CF from any station] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [CF from any station] destination_number(1276) =~ /^\*76$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->CF cancel from any station] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [CF cancel from any station] destination_number(1276) =~ /^\*77$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->NACF from my station] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [NACF from my station] destination_number(1276) =~ /^\*74$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->NACF cancel from my station] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [NACF cancel from my station] destination_number(1276) =~ /^\*75$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Add_Member_To_Queue] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Add_Member_To_Queue] destination_number(1276) =~ /^\*51$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Delete_Member_From_Queue] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Delete_Member_From_Queue] destination_number(1276) =~ /^\*52$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Pickup_Call_From_Queue] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Pickup_Call_From_Queue] destination_number(1276) =~ /^\*53$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->dial-by-name] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [dial-by-name] destination_number(1276) =~ /^\*40$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->conference_withpin] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [conference_withpin] destination_number(1276) =~ /^\*3560$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Voicemail_check] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Voicemail_check] caller_id_number(142) =~ /^(1276)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->check_availability] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [check_availability] destination_number(1276) =~ /^hkjhk(1[0-9][0-9][0-9]|[1-2][0-9][0-9])$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->is_check_availability] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [is_check_availability] available_to_call() =~ /true/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->osbridge] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [osbridge] destination_number(1276) =~ /(^\d{3,5})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(proxy_media=false)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(continue_on_fail=true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action export(dialed_extension=1276) INLINE
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(dialed_extension=1276)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [dialed_extension]=[1276]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action export(domain_name=${domain_name})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(accountcode=${user_data(${dialed_extension}@${domain_name} var accountcode)})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action export(available_to_call=true) INLINE
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(available_to_call=true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [available_to_call]=[true]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action log(Extensions ${dialed_extension} is ${available_to_call})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(nacf_target=${db(select/${domain_name}-NACF/1276)}) INLINE
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(nacf_target=)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [nacf_target]=[UNDEF]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [osbridge] ${recording}(off) =~ /^on$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [osbridge] ${available_to_call}(true) =~ /^true$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action bind_meta_app(1 b s execute_extension::dx XML features)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 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)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action bind_meta_app(3 b s execute_extension::cf XML features)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action bind_meta_app(4 b s execute_extension::attented_xfer XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action bind_meta_app(5 b s execute_extension::attented_xfer_to_vm XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action bind_meta_app(6 b s execute_extension::blind_xfer_to XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action bind_meta_app(9 b s execute_extension::call_park XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action export(sip_contact_user=${sip_from_user})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(ringback=${us-ring})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(transfer_ringback=local_stream://moh)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(call_timeout=35)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action set(hangup_after_bridge=true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action bridge(user/${dialed_extension}@${domain_name})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action sleep(1000)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [osbridge] ${nacf_target}() =~ /^\d{3,7}$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [osbridge] ${nacf_target}() =~ /^\d{10,15}$/ break=never
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Absolute Condition [osbridge]
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action answer()
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action sleep(500)
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action voicemail(default ${domain_name} ${dialed_extension})
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Local_Extension_VM] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Local_Extension_VM] destination_number(1276) =~ /^vm-(\d{3,5})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->attented_xfer_to_vm] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [attented_xfer_to_vm] destination_number(1276) =~ /^attented_xfer_to_vm$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->attented_xfer] continue=true
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [attented_xfer] destination_number(1276) =~ /^attented_xfer$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->blind_xfer_to] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [blind_xfer_to] destination_number(1276) =~ /^blind_xfer_to$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Local_Extension] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Local_Extension] destination_number(1276) =~ /^disable-(1[0-9][0-9][0-9]|[1-2][0-9][0-9])$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Local_Extension_Skinny] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Local_Extension_Skinny] destination_number(1276) =~ /^(11[01][0-9])$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->park-in] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [park-in] destination_number(1276) =~ /^call_park$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->park-out] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [park-out] destination_number(1276) =~ /^\*(85\d\d)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->vmain] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [vmain] destination_number(1276) =~ /^vmain$|^\*98$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->sip_uri] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [sip_uri] destination_number(1276) =~ /^sip:(.*)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->local_uri] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [local_uri] destination_number(1276) =~ /^(local_.*)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->nb_conferences] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [nb_conferences] destination_number(1276) =~ /^\*(30\d{2,4})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->wb_conferences] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [wb_conferences] destination_number(1276) =~ /^\*(31\d{2})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->uwb_conferences] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [uwb_conferences] destination_number(1276) =~ /^\*(32\d{2})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->cdquality_conferences] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [cdquality_conferences] destination_number(1276) =~ /^\*(33\d{2})$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->mad_boss_intercom] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [mad_boss_intercom] destination_number(1276) =~ /^0911$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->mad_boss_intercom] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [mad_boss_intercom] destination_number(1276) =~ /^0912$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->mad_boss] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [mad_boss] destination_number(1276) =~ /^0913$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->queupark] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [queupark] destination_number(1276) =~ /^\*(5902)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->queueunpark] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [queueunpark] destination_number(1276) =~ /^\*(5903)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->park] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [park] destination_number(1276) =~ /^\*5900$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->unpark] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [unpark] destination_number(1276) =~ /^\*(5901)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->valet_park] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [valet_park] destination_number(1276) =~ /^(6000)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->valet_park] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [valet_park] destination_number(1276) =~ /^(60\d[1-9])$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->park] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [park] destination_number(1276) =~ /park\+(\d+)/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->unpark] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [unpark] destination_number(1276) =~ /^parking$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->park] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [park] destination_number(1276) =~ /callpark/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->unpark] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [unpark] destination_number(1276) =~ /pickup/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->wait] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [wait] destination_number(1276) =~ /^wait$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->fax_receive] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [fax_receive] destination_number(1276) =~ /^fax.*$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->fax_transmit] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [fax_transmit] destination_number(1276) =~ /^9179$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->ringback_180] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [ringback_180] destination_number(1276) =~ /^9180$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->ringback_183_uk_ring] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [ringback_183_uk_ring] destination_number(1276) =~ /^\*9181$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->ringback_183_music_ring] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [ringback_183_music_ring] destination_number(1276) =~ /^\*9182$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->ringback_post_answer_uk_ring] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(1276) =~ /^\*9183$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->ringback_post_answer_music] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [ringback_post_answer_music] destination_number(1276) =~ /^\*9184$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->show_info] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [show_info] destination_number(1276) =~ /^\*9192$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->video_record] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [video_record] destination_number(1276) =~ /^\*9193$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->video_playback] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [video_playback] destination_number(1276) =~ /^\*9194$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->delay_echo] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [delay_echo] destination_number(1276) =~ /^\*9195$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->echo] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [echo] destination_number(1276) =~ /^\*9196$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->milliwatt] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [milliwatt] destination_number(1276) =~ /^\*9197$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->tone_stream] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [tone_stream] destination_number(1276) =~ /^\*9198$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->zrtp_enrollement] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [zrtp_enrollement] destination_number(1276) =~ /^\*9787$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->hold_music] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [hold_music] destination_number(1276) =~ /^\*9664$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->laugh break] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [laugh break] destination_number(1276) =~ /^\*9386$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->101] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [101] destination_number(1276) =~ /^101$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->pizza_demo] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [pizza_demo] destination_number(1276) =~ /^(pizza|74992)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Talking Clock Time] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Talking Clock Time] destination_number(1276) =~ /^9170$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Talking Clock Date] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Talking Clock Date] destination_number(1276) =~ /^9171$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->Talking Clock Date and Time] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [Talking Clock Date and Time] destination_number(1276) =~ /^9172$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->local.example.com] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [local.example.com] ${toll_allow}() =~ /local/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->domestic.example.com] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [domestic.example.com] ${toll_allow}() =~ /domestic/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->international.example.com] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [international.example.com] ${toll_allow}() =~ /international/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->conference] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [conference] destination_number(1276) =~ /^112233$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->didforsale_ivr] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [didforsale_ivr] destination_number(1276) =~ /5566/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->domian_ivr] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (FAIL) [domian_ivr] destination_number(1276) =~ /ivr(1949.*)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 parsing [default->enum] continue=false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [enum] ${module_exists(mod_enum)}(true) =~ /true/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Regex (PASS) [enum] destination_number(1276) =~ /^(.*)$/ break=on-false
03d4582f-ed06-4268-a70b-0dd9307fb4cf Dialplan: sofia/internal/142@domain.example.com:5660 Action transfer(1276 enum)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/142@domain.example.com:5660) State Change CS_ROUTING -> CS_EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/142@domain.example.com:5660) State ROUTING going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/142@domain.example.com:5660) State EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] mod_sofia.c:196 sofia/internal/142@domain.example.com:5660 SOFIA EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_core_state_machine.c:258 sofia/internal/142@domain.example.com:5660 Standard EXECUTE
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 hash(insert/domain.example.com-spymap/142/03d4582f-ed06-4268-a70b-0dd9307fb4cf)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 hash(insert/domain.example.com-last_dial/142/1276)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 hash(insert/domain.example.com-last_dial/global/03d4582f-ed06-4268-a70b-0dd9307fb4cf)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(RFC2822_DATE=Sat, 13 Feb 2016 09:21:55 -0800)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [RFC2822_DATE]=[Sat, 13 Feb 2016 09:21:55 -0800]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(proxy_media=false)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [proxy_media]=[false]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(continue_on_fail=true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [continue_on_fail]=[true]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(domain_name=domain.example.com)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [domain_name]=[domain.example.com]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
2016-02-13 09:21:55.667790 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:21:55.667790 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(accountcode=17)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [accountcode]=[17]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 log(Extensions 1276 is true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [DEBUG] mod_dptools.c:1692 1276 is true
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bind_meta_app(1 b s execute_extension::dx XML features)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *1 execute_extension::dx XML features
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.667790 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bind_meta_app(2 b s record_session::/usr/local/freeswitch/var/lib/freeswitch/recordings/domain.example.com/2016-02-13-09-21-55.03d4582f-ed06-4268-a70b-0dd9307fb4cf.wav)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [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-21-55.03d4582f-ed06-4268-a70b-0dd9307fb4cf.wav
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bind_meta_app(3 b s execute_extension::cf XML features)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *3 execute_extension::cf XML features
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bind_meta_app(4 b s execute_extension::attented_xfer XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *4 execute_extension::attented_xfer XML default
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bind_meta_app(5 b s execute_extension::attented_xfer_to_vm XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *5 execute_extension::attented_xfer_to_vm XML default
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bind_meta_app(6 b s execute_extension::blind_xfer_to XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *6 execute_extension::blind_xfer_to XML default
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bind_meta_app(9 b s execute_extension::call_park XML default)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *9 execute_extension::call_park XML default
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 export(sip_contact_user=142)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [sip_contact_user]=[142]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(ringback=%(2000,4000,440,480))
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [ringback]=[%(2000,4000,440,480)]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(transfer_ringback=local_stream://moh)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [transfer_ringback]=[local_stream://moh]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(call_timeout=35)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [call_timeout]=[35]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 set(hangup_after_bridge=true)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] mod_dptools.c:1498 SET sofia/internal/142@domain.example.com:5660 [hangup_after_bridge]=[true]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf EXECUTE sofia/internal/142@domain.example.com:5660 bridge(user/1276@domain.example.com)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [ALERT] switch_core_session.c:2781 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [caller-id-in-from]=[true] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [sip_cid_type]=[none] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [skip_cdr_causes]=[LOSE_RACE] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [accountcode]=[17] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [dialed_extension]=[1276] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [available_to_call]=[true] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 13 Feb 2016 09:21:55 -0800] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [sip_contact_user]=[142] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
2016-02-13 09:21:55.677785 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:21:55.677785 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [caller-id-in-from]=[true] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [sip_cid_type]=[none] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [skip_cdr_causes]=[LOSE_RACE] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [accountcode]=[17] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [dialed_extension]=[1276] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [available_to_call]=[true] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 13 Feb 2016 09:21:55 -0800] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [domain_name]=[domain.example.com] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_channel.c:1247 sofia/internal/142@domain.example.com:5660 EXPORTING[export_vars] [sip_contact_user]=[142] to event
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.677785 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1276@192.168.1.168:12113 [997ba225-1002-4fec-9b89-f7e1986ebfca]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] mod_sofia.c:4776 (sofia/internal/1276@192.168.1.168:12113) State Change CS_NEW -> CS_INIT
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@192.168.1.168:12113) Running State Change CS_INIT
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1276@192.168.1.168:12113) State INIT
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] mod_sofia.c:88 sofia/internal/1276@192.168.1.168:12113 SOFIA INIT
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] sofia_glue.c:1228 sip:1276@68.5.94.63:12113 Setting proxy route to sofia/internal/1276@192.168.1.168:12113
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] sofia_glue.c:1257 sofia/internal/1276@192.168.1.168:12113 sending invite version: 1.6.6  64bit
997ba225-1002-4fec-9b89-f7e1986ebfca Local SDP:
997ba225-1002-4fec-9b89-f7e1986ebfca v=0
997ba225-1002-4fec-9b89-f7e1986ebfca o=FreeSWITCH 1455351947 1455351948 IN IP4 219.206.20.22
997ba225-1002-4fec-9b89-f7e1986ebfca s=FreeSWITCH
997ba225-1002-4fec-9b89-f7e1986ebfca c=IN IP4 219.206.20.22
997ba225-1002-4fec-9b89-f7e1986ebfca t=0 0
997ba225-1002-4fec-9b89-f7e1986ebfca m=audio 32168 RTP/AVP 0 8 101 13
997ba225-1002-4fec-9b89-f7e1986ebfca a=rtpmap:0 PCMU/8000
997ba225-1002-4fec-9b89-f7e1986ebfca a=rtpmap:8 PCMA/8000
997ba225-1002-4fec-9b89-f7e1986ebfca a=rtpmap:101 telephone-event/8000
997ba225-1002-4fec-9b89-f7e1986ebfca a=fmtp:101 0-16
997ba225-1002-4fec-9b89-f7e1986ebfca a=rtpmap:13 CN/8000
997ba225-1002-4fec-9b89-f7e1986ebfca a=ptime:20
997ba225-1002-4fec-9b89-f7e1986ebfca a=sendrecv
997ba225-1002-4fec-9b89-f7e1986ebfca 
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1276@192.168.1.168:12113 Standard INIT
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1276@192.168.1.168:12113) State Change CS_INIT -> CS_ROUTING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1276@192.168.1.168:12113) State INIT going to sleep
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@192.168.1.168:12113) Running State Change CS_ROUTING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@192.168.1.168:12113 entering state [calling][0]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1276@192.168.1.168:12113) State ROUTING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] mod_sofia.c:141 sofia/internal/1276@192.168.1.168:12113 SOFIA ROUTING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1276@192.168.1.168:12113) State Change CS_ROUTING -> CS_CONSUME_MEDIA
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1276@192.168.1.168:12113) State ROUTING going to sleep
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@192.168.1.168:12113) Running State Change CS_CONSUME_MEDIA
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/1276@192.168.1.168:12113) State CONSUME_MEDIA
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/1276@192.168.1.168:12113) State CONSUME_MEDIA going to sleep
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.677785 [ALERT] switch_core_state_machine.c:590 sofia/internal/1276@192.168.1.168:12113 session thread sleep state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.907833 [ALERT] switch_core_state_machine.c:594 sofia/internal/1276@192.168.1.168:12113 session thread wake state: CS_CONSUME_MEDIA!
2016-02-13 09:21:55.907833 [ALERT] sofia.c:1214 sofia/internal/1276@192.168.1.168:12113 Same Callee ID "Outbound Call" <1276>
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.907833 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@192.168.1.168:12113 entering state [proceeding][180]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.907833 [NOTICE] sofia.c:6862 Ring-Ready sofia/internal/1276@192.168.1.168:12113!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.907833 [DEBUG] switch_channel.c:3340 (sofia/internal/1276@192.168.1.168:12113) Callstate Change DOWN -> RINGING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.907833 [ALERT] sofia.c:6862 sofia/internal/1276@192.168.1.168:12113 receive message [RING_EVENT]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:21:55.907833 [ALERT] switch_core_state_machine.c:590 sofia/internal/1276@192.168.1.168:12113 session thread sleep state: CS_CONSUME_MEDIA!
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [ALERT] switch_ivr_originate.c:1216 sofia/internal/142@domain.example.com:5660 receive message [PROGRESS]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [INFO] switch_ivr_originate.c:1216 Sending early media
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [ALERT] switch_core_media.c:376 Found peer channel; propagating zrtp-hash if set
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 101@8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/142@domain.example.com:5660 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_codec.c:111 sofia/internal/142@domain.example.com:5660 Original read codec set to PCMU:0
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 101@8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:4485 sofia/internal/142@domain.example.com:5660 Set 2833 dtmf send payload to 101 recv payload to 101
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/142@domain.example.com:5660] 219.206.20.22 port 30674 -> 192.168.33.116 port 50028 codec: 0 ms: 20
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:1939 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:6332 sofia/internal/142@domain.example.com:5660 Set 2833 dtmf send payload to 101
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:6339 sofia/internal/142@domain.example.com:5660 Set 2833 dtmf receive payload to 101
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_media.c:6362 sofia/internal/142@domain.example.com:5660 Set rtp dtmf delay to 60
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/142@domain.example.com:5660!
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_channel.c:3468 (sofia/internal/142@domain.example.com:5660) Callstate Change RINGING -> EARLY
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [ALERT] sofia_media.c:92 sofia/internal/142@domain.example.com:5660 receive message [PROGRESS_EVENT]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] mod_sofia.c:2330 Ring SDP:
03d4582f-ed06-4268-a70b-0dd9307fb4cf v=0
03d4582f-ed06-4268-a70b-0dd9307fb4cf o=FreeSWITCH 1455353441 1455353442 IN IP4 219.206.20.22
03d4582f-ed06-4268-a70b-0dd9307fb4cf s=FreeSWITCH
03d4582f-ed06-4268-a70b-0dd9307fb4cf c=IN IP4 219.206.20.22
03d4582f-ed06-4268-a70b-0dd9307fb4cf t=0 0
03d4582f-ed06-4268-a70b-0dd9307fb4cf m=audio 30674 RTP/AVP 0 101
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:0 PCMU/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:101 telephone-event/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=fmtp:101 0-16
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=ptime:20
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=sendrecv
03d4582f-ed06-4268-a70b-0dd9307fb4cf 
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_ivr_originate.c:1274 Raw Codec Activation Success L16@8000hz 1 channel 20ms
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_core_codec.c:221 sofia/internal/142@domain.example.com:5660 Push codec L16:100
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.927830 [DEBUG] switch_ivr_originate.c:1343 Play Ringback Tone [%(2000,4000,440,480)]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.947831 [DEBUG] sofia.c:6760 Channel sofia/internal/142@domain.example.com:5660 entering state [early][183]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.947831 [ALERT] switch_channel.c:3507 sofia/internal/142@domain.example.com:5660 receive message [AUDIO_SYNC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:55.947831 [ALERT] switch_core_io.c:1135 sofia/internal/142@domain.example.com:5660 receive message [TRANSCODING_NECESSARY]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:21:56.817831 [INFO] switch_rtp.c:6616 Auto Changing audio port from 192.168.33.116:50028 to 73.170.102.43:50028
2016-02-13 09:22:00.847852 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:22:00.847852 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
2016-02-13 09:22:03.957850 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:22:03.957850 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_state_machine.c:594 sofia/internal/1276@192.168.1.168:12113 session thread wake state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:376 Found peer channel; propagating zrtp-hash if set
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
2016-02-13 09:22:04.037826 [ALERT] sofia.c:1214 sofia/internal/1276@192.168.1.168:12113 Same Callee ID "Outbound Call" <1276>
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@192.168.1.168:12113 entering state [completing][200]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] sofia.c:6770 Remote SDP:
997ba225-1002-4fec-9b89-f7e1986ebfca v=0
997ba225-1002-4fec-9b89-f7e1986ebfca o=1276 82204 1 IN IP4 192.168.1.168
997ba225-1002-4fec-9b89-f7e1986ebfca s=-
997ba225-1002-4fec-9b89-f7e1986ebfca c=IN IP4 192.168.1.168
997ba225-1002-4fec-9b89-f7e1986ebfca t=0 0
997ba225-1002-4fec-9b89-f7e1986ebfca m=audio 8000 RTP/AVP 0 96
997ba225-1002-4fec-9b89-f7e1986ebfca a=rtpmap:0 PCMU/8000
997ba225-1002-4fec-9b89-f7e1986ebfca a=rtpmap:96 telephone-event/8000
997ba225-1002-4fec-9b89-f7e1986ebfca a=ptime:20
997ba225-1002-4fec-9b89-f7e1986ebfca 
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_state_machine.c:590 sofia/internal/1276@192.168.1.168:12113 session thread sleep state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_state_machine.c:594 sofia/internal/1276@192.168.1.168:12113 session thread wake state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] sofia.c:6760 Channel sofia/internal/1276@192.168.1.168:12113 entering state [ready][200]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:413 Looking for zrtp-hash
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:368 Deciding whether to pass zrtp-hash between legs
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:376 Found peer channel; propagating zrtp-hash if set
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_media.c:303 Deciding whether to pass zrtp-hash between a-leg and b-leg
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 96@8000
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/1276@192.168.1.168:12113 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_codec.c:111 sofia/internal/1276@192.168.1.168:12113 Original read codec set to PCMU:0
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 96@8000
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:4485 sofia/internal/1276@192.168.1.168:12113 Set 2833 dtmf send payload to 96 recv payload to 101
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/1276@192.168.1.168:12113] 219.206.20.22 port 32168 -> 192.168.1.168 port 8000 codec: 0 ms: 20
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:1939 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:6332 sofia/internal/1276@192.168.1.168:12113 Set 2833 dtmf send payload to 96
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:6339 sofia/internal/1276@192.168.1.168:12113 Set 2833 dtmf receive payload to 101
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_core_media.c:6362 sofia/internal/1276@192.168.1.168:12113 Set rtp dtmf delay to 60
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [NOTICE] sofia.c:7724 Channel [sofia/internal/1276@192.168.1.168:12113] has been answered
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [DEBUG] switch_channel.c:3767 (sofia/internal/1276@192.168.1.168:12113) Callstate Change RINGING -> ACTIVE
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] sofia.c:7724 sofia/internal/1276@192.168.1.168:12113 receive message [ANSWER_EVENT]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.037826 [ALERT] switch_core_state_machine.c:590 sofia/internal/1276@192.168.1.168:12113 session thread sleep state: CS_CONSUME_MEDIA!
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [DEBUG] switch_core_codec.c:246 sofia/internal/142@domain.example.com:5660 Restore previous codec PCMU:0.
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_originate.c:3550 sofia/internal/142@domain.example.com:5660 receive message [ANSWER]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [DEBUG] mod_sofia.c:799 Local SDP sofia/internal/142@domain.example.com:5660:
03d4582f-ed06-4268-a70b-0dd9307fb4cf v=0
03d4582f-ed06-4268-a70b-0dd9307fb4cf o=FreeSWITCH 1455353441 1455353443 IN IP4 219.206.20.22
03d4582f-ed06-4268-a70b-0dd9307fb4cf s=FreeSWITCH
03d4582f-ed06-4268-a70b-0dd9307fb4cf c=IN IP4 219.206.20.22
03d4582f-ed06-4268-a70b-0dd9307fb4cf t=0 0
03d4582f-ed06-4268-a70b-0dd9307fb4cf m=audio 30674 RTP/AVP 0 101
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:0 PCMU/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=rtpmap:101 telephone-event/8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=fmtp:101 0-16
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=ptime:20
03d4582f-ed06-4268-a70b-0dd9307fb4cf a=sendrecv
03d4582f-ed06-4268-a70b-0dd9307fb4cf 
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [NOTICE] switch_ivr_originate.c:3550 Channel [sofia/internal/142@domain.example.com:5660] has been answered
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [DEBUG] switch_channel.c:3767 (sofia/internal/142@domain.example.com:5660) Callstate Change EARLY -> ACTIVE
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_originate.c:3550 sofia/internal/142@domain.example.com:5660 receive message [ANSWER_EVENT]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [DEBUG] sofia.c:6760 Channel sofia/internal/142@domain.example.com:5660 entering state [completed][200]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/1276@192.168.1.168:12113]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_originate.c:3944 sofia/internal/142@domain.example.com:5660 receive message [AUDIO_SYNC]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_core_state_machine.c:594 sofia/internal/1276@192.168.1.168:12113 session thread wake state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_originate.c:3941 sofia/internal/1276@192.168.1.168:12113 receive message [AUDIO_SYNC]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_core_state_machine.c:590 sofia/internal/1276@192.168.1.168:12113 session thread sleep state: CS_CONSUME_MEDIA!
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/1276@192.168.1.168:12113]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_originate.c:3944 sofia/internal/142@domain.example.com:5660 receive message [AUDIO_SYNC]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_core_state_machine.c:594 sofia/internal/1276@192.168.1.168:12113 session thread wake state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_originate.c:3941 sofia/internal/1276@192.168.1.168:12113 receive message [AUDIO_SYNC]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_core_state_machine.c:590 sofia/internal/1276@192.168.1.168:12113 session thread sleep state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_core_state_machine.c:594 sofia/internal/1276@192.168.1.168:12113 session thread wake state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_bridge.c:1452 sofia/internal/1276@192.168.1.168:12113 receive message [AUDIO_SYNC]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_core_state_machine.c:590 sofia/internal/1276@192.168.1.168:12113 session thread sleep state: CS_CONSUME_MEDIA!
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [ALERT] switch_ivr.c:217 sofia/internal/142@domain.example.com:5660 receive message [AUDIO_SYNC]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_bridge.c:1451 sofia/internal/142@domain.example.com:5660 receive message [AUDIO_SYNC]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_bridge.c:1551 sofia/internal/1276@192.168.1.168:12113 receive message [BRIDGE]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [DEBUG] switch_core_media.c:9156 sofia/internal/1276@192.168.1.168:12113 PAUSE Jitterbuffer
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [ALERT] switch_ivr_bridge.c:1559 sofia/internal/142@domain.example.com:5660 receive message [BRIDGE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.047852 [DEBUG] switch_core_media.c:9156 sofia/internal/142@domain.example.com:5660 PAUSE Jitterbuffer
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [DEBUG] switch_ivr_bridge.c:1591 (sofia/internal/1276@192.168.1.168:12113) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [ALERT] switch_core_state_machine.c:594 sofia/internal/1276@192.168.1.168:12113 session thread wake state: CS_CONSUME_MEDIA!
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@192.168.1.168:12113) Running State Change CS_EXCHANGE_MEDIA
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1276@192.168.1.168:12113) State EXCHANGE_MEDIA
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.047852 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.067829 [ALERT] switch_core_io.c:420 sofia/internal/142@domain.example.com:5660 receive message [TRANSCODING_NECESSARY]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.087830 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.257832 [INFO] switch_rtp.c:6616 Auto Changing audio port from 192.168.1.168:8000 to 68.5.94.63:8000
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.587826 [DEBUG] sofia.c:6760 Channel sofia/internal/142@domain.example.com:5660 entering state [ready][200]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:04.597832 [ALERT] switch_ivr_bridge.c:283 sofia/internal/1276@192.168.1.168:12113 receive message [DISPLAY]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:04.607833 [ALERT] switch_ivr_bridge.c:283 sofia/internal/142@domain.example.com:5660 receive message [DISPLAY]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:06.197829 [ALERT] switch_rtp.c:1612 sofia/internal/1276@192.168.1.168:12113 Got: audio seq 53854 but expected: 53852 lost: 2
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:06.617854 [ALERT] switch_rtp.c:1612 sofia/internal/1276@192.168.1.168:12113 Got: audio seq 53866 but expected: 53865 lost: 1

997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:08.737851 [ALERT] switch_rtp.c:1612 sofia/internal/1276@192.168.1.168:12113 Got: audio seq 53961 but expected: 53960 lost: 1
2016-02-13 09:22:08.827825 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:22:08.827825 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:10.097827 [ALERT] switch_rtp.c:1612 sofia/internal/1276@192.168.1.168:12113 Got: audio seq 53977 but expected: 53974 lost: 3
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:10.637831 [ALERT] switch_rtp.c:1612 sofia/internal/1276@192.168.1.168:12113 Got: audio seq 53990 but expected: 53989 lost: 1
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:11.717833 [ALERT] switch_rtp.c:1612 sofia/internal/1276@192.168.1.168:12113 Got: audio seq 54036 but expected: 54035 lost: 1
2016-02-13 09:22:11.967873 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f41ac03e390 Connected.
2016-02-13 09:22:11.967873 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f41ac03e390 released.
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.107862 [NOTICE] sofia.c:952 Hangup sofia/internal/142@domain.example.com:5660 [CS_EXECUTE] [NORMAL_CLEARING]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.107862 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/142@domain.example.com:5660]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_ivr_bridge.c:699 sofia/internal/142@domain.example.com:5660 ending bridge by request from write function
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1276@192.168.1.168:12113]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [NOTICE] switch_ivr_bridge.c:881 Hangup sofia/internal/1276@192.168.1.168:12113 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1276@192.168.1.168:12113) State EXCHANGE_MEDIA going to sleep
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@192.168.1.168:12113) Running State Change CS_HANGUP
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1276@192.168.1.168:12113) Callstate Change ACTIVE -> HANGUP
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1276@192.168.1.168:12113) State HANGUP
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] mod_sofia.c:425 sofia/internal/1276@192.168.1.168:12113 Overriding SIP cause 480 with 200 from the other leg
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1276@192.168.1.168:12113 hanging up, cause: NORMAL_CLEARING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] mod_sofia.c:484 Sending BYE to sofia/internal/1276@192.168.1.168:12113
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1276@192.168.1.168:12113 Standard HANGUP, cause: NORMAL_CLEARING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1276@192.168.1.168:12113) State HANGUP going to sleep
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [ALERT] switch_ivr_bridge.c:1689 sofia/internal/1276@192.168.1.168:12113 receive message [UNBRIDGE]
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_ivr_bridge.c:1689 sofia/internal/1276@192.168.1.168:12113 skip receive message [UNBRIDGE] (channel is hungup already)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [ALERT] switch_ivr_bridge.c:1692 sofia/internal/142@domain.example.com:5660 receive message [UNBRIDGE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_ivr_bridge.c:1692 sofia/internal/142@domain.example.com:5660 skip receive message [UNBRIDGE] (channel is hungup already)
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1276@192.168.1.168:12113) State Change CS_HANGUP -> CS_REPORTING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1276@192.168.1.168:12113) Running State Change CS_REPORTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [ALERT] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 receive message [APPLICATION_EXEC_COMPLETE]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_session.c:2796 sofia/internal/142@domain.example.com:5660 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/142@domain.example.com:5660) State EXECUTE going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_HANGUP
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1276@192.168.1.168:12113) State REPORTING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1276@192.168.1.168:12113 Standard REPORTING, cause: NORMAL_CLEARING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1276@192.168.1.168:12113) State REPORTING going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/142@domain.example.com:5660) Callstate Change ACTIVE -> HANGUP
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1276@192.168.1.168:12113) State Change CS_REPORTING -> CS_DESTROY
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_session.c:1646 Session 1855 (sofia/internal/1276@192.168.1.168:12113) Locked, Waiting on external entities
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [NOTICE] switch_core_session.c:1664 Session 1855 (sofia/internal/1276@192.168.1.168:12113) Ended
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1276@192.168.1.168:12113 [CS_DESTROY]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/142@domain.example.com:5660) State HANGUP
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] mod_sofia.c:431 Channel sofia/internal/142@domain.example.com:5660 hanging up, cause: NORMAL_CLEARING
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1276@192.168.1.168:12113) Running State Change CS_DESTROY
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1276@192.168.1.168:12113) State DESTROY
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] mod_sofia.c:341 sofia/internal/1276@192.168.1.168:12113 SOFIA DESTROY
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1276@192.168.1.168:12113 Standard DESTROY
997ba225-1002-4fec-9b89-f7e1986ebfca 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1276@192.168.1.168:12113) State DESTROY going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:60 sofia/internal/142@domain.example.com:5660 Standard HANGUP, cause: NORMAL_CLEARING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/142@domain.example.com:5660) State HANGUP going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/142@domain.example.com:5660) State Change CS_HANGUP -> CS_REPORTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/142@domain.example.com:5660) Running State Change CS_REPORTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/142@domain.example.com:5660) State REPORTING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.117826 [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 (73.170.102.43, 1276, 03d4582f-ed06-4268-a70b-0dd9307fb4cf, inbound, 03d4582f-ed06-4268-a70b-0dd9307fb4cf, 03d4582f-ed06-4268-a70b-0dd9307fb4cf, 17, 2016-02-13 09:22:12, 142, 8, 142, 2016-02-13 09:21:55, NORMAL_CLEARING, 17, REQUEST_PARAMS)
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_state_machine.c:104 sofia/internal/142@domain.example.com:5660 Standard REPORTING, cause: NORMAL_CLEARING
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/142@domain.example.com:5660) State REPORTING going to sleep
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/142@domain.example.com:5660) State Change CS_REPORTING -> CS_DESTROY
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_session.c:1646 Session 1854 (sofia/internal/142@domain.example.com:5660) Locked, Waiting on external entities
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [NOTICE] switch_core_session.c:1664 Session 1854 (sofia/internal/142@domain.example.com:5660) Ended
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/142@domain.example.com:5660 [CS_DESTROY]
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/142@domain.example.com:5660) Running State Change CS_DESTROY
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/142@domain.example.com:5660) State DESTROY
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] mod_sofia.c:341 sofia/internal/142@domain.example.com:5660 SOFIA DESTROY
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_state_machine.c:111 sofia/internal/142@domain.example.com:5660 Standard DESTROY
03d4582f-ed06-4268-a70b-0dd9307fb4cf 2016-02-13 09:22:12.127828 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/142@domain.example.com:5660) State DESTROY going to sleep