2017-02-20 15:51:01.441054 [NOTICE] switch_channel.c:1091 New Channel sofia/external/1031@freeswitch_server_ip [47287f6c-f756-11e6-aaac-7f26e6f53282]
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1031@freeswitch_server_ip) Running State Change CS_NEW
2017-02-20 15:51:01.441054 [DEBUG] sofia.c:9240 sofia/external/1031@freeswitch_server_ip receiving invite from 23.239.85.20:5077 version: 1.6.5 git 70b8c17 2015-11-20 20:57:50Z 64bit
2017-02-20 15:51:01.441054 [DEBUG] sofia.c:6750 Channel sofia/external/1031@freeswitch_server_ip entering state [received][100]
2017-02-20 15:51:01.441054 [DEBUG] sofia.c:6760 Remote SDP:
v=0
o=sipcli-Session 1181448858 746045291 IN IP4 23.239.85.20
s=sipcli
c=IN IP4 23.239.85.20
t=0 0
m=audio 5083 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

2017-02-20 15:51:01.441054 [DEBUG] sofia.c:7115 (sofia/external/1031@freeswitch_server_ip) State Change CS_NEW -> CS_INIT
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:492 (sofia/external/1031@freeswitch_server_ip) State NEW
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1031@freeswitch_server_ip) Running State Change CS_INIT
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:516 (sofia/external/1031@freeswitch_server_ip) State INIT
2017-02-20 15:51:01.441054 [DEBUG] mod_sofia.c:88 sofia/external/1031@freeswitch_server_ip SOFIA INIT
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:40 sofia/external/1031@freeswitch_server_ip Standard INIT
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:48 (sofia/external/1031@freeswitch_server_ip) State Change CS_INIT -> CS_ROUTING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:516 (sofia/external/1031@freeswitch_server_ip) State INIT going to sleep
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1031@freeswitch_server_ip) Running State Change CS_ROUTING
2017-02-20 15:51:01.441054 [DEBUG] switch_channel.c:2239 (sofia/external/1031@freeswitch_server_ip) Callstate Change DOWN -> RINGING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:532 (sofia/external/1031@freeswitch_server_ip) State ROUTING
2017-02-20 15:51:01.441054 [DEBUG] mod_sofia.c:141 sofia/external/1031@freeswitch_server_ip SOFIA ROUTING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:166 sofia/external/1031@freeswitch_server_ip Standard ROUTING
2017-02-20 15:51:01.441054 [INFO] mod_dialplan_xml.c:637 Processing 1031 <1031>->9700972598409238 in context public
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->unloop] continue=false
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->outside_call] continue=true
Dialplan: sofia/external/1031@freeswitch_server_ip Absolute Condition [outside_call]
Dialplan: sofia/external/1031@freeswitch_server_ip Action set(outside_call=true)
Dialplan: sofia/external/1031@freeswitch_server_ip Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->call_debug] continue=true
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->public_extensions] continue=false
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (FAIL) [public_extensions] destination_number(9700972598409238) =~ /^(2\d\d\d)$/ break=on-false
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->public_extensions] continue=false
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (FAIL) [public_extensions] destination_number(9700972598409238) =~ /^(5\d\d\d)$/ break=on-false
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->public_extensions] continue=false
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (FAIL) [public_extensions] destination_number(9700972598409238) =~ /^(7\d\d\d)$/ break=on-false
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->public_extensions] continue=false
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (FAIL) [public_extensions] destination_number(9700972598409238) =~ /^(3\d\d\d)$/ break=on-false
Dialplan: sofia/external/1031@freeswitch_server_ip parsing [public->vmain] continue=false
Dialplan: sofia/external/1031@freeswitch_server_ip Regex (FAIL) [vmain] destination_number(9700972598409238) =~ /^vmain$|^4000$|^\*98$/ break=on-false
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:216 (sofia/external/1031@freeswitch_server_ip) State Change CS_ROUTING -> CS_EXECUTE
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:532 (sofia/external/1031@freeswitch_server_ip) State ROUTING going to sleep
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1031@freeswitch_server_ip) Running State Change CS_EXECUTE
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:539 (sofia/external/1031@freeswitch_server_ip) State EXECUTE
2017-02-20 15:51:01.441054 [DEBUG] mod_sofia.c:196 sofia/external/1031@freeswitch_server_ip SOFIA EXECUTE
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:258 sofia/external/1031@freeswitch_server_ip Standard EXECUTE
EXECUTE sofia/external/1031@freeswitch_server_ip set(outside_call=true)
2017-02-20 15:51:01.441054 [DEBUG] mod_dptools.c:1498 SET sofia/external/1031@freeswitch_server_ip [outside_call]=[true]
EXECUTE sofia/external/1031@freeswitch_server_ip export(RFC2822_DATE=Mon, 20 Feb 2017 15:51:01 +0530)
2017-02-20 15:51:01.441054 [DEBUG] switch_channel.c:1283 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 20 Feb 2017 15:51:01 +0530]
2017-02-20 15:51:01.441054 [NOTICE] switch_core_state_machine.c:315 sofia/external/1031@freeswitch_server_ip has executed the last dialplan instruction, hanging up.
2017-02-20 15:51:01.441054 [NOTICE] switch_core_state_machine.c:317 Hangup sofia/external/1031@freeswitch_server_ip [CS_EXECUTE] [NORMAL_CLEARING]
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:539 (sofia/external/1031@freeswitch_server_ip) State EXECUTE going to sleep
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1031@freeswitch_server_ip) Running State Change CS_HANGUP
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:739 (sofia/external/1031@freeswitch_server_ip) Callstate Change RINGING -> HANGUP
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:741 (sofia/external/1031@freeswitch_server_ip) State HANGUP
2017-02-20 15:51:01.441054 [DEBUG] mod_sofia.c:431 Channel sofia/external/1031@freeswitch_server_ip hanging up, cause: NORMAL_CLEARING
2017-02-20 15:51:01.441054 [DEBUG] mod_sofia.c:568 Responding to INVITE with: 480
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:60 sofia/external/1031@freeswitch_server_ip Standard HANGUP, cause: NORMAL_CLEARING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:741 (sofia/external/1031@freeswitch_server_ip) State HANGUP going to sleep
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:508 (sofia/external/1031@freeswitch_server_ip) State Change CS_HANGUP -> CS_REPORTING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1031@freeswitch_server_ip) Running State Change CS_REPORTING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:827 (sofia/external/1031@freeswitch_server_ip) State REPORTING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:104 sofia/external/1031@freeswitch_server_ip Standard REPORTING, cause: NORMAL_CLEARING
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:827 (sofia/external/1031@freeswitch_server_ip) State REPORTING going to sleep
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:499 (sofia/external/1031@freeswitch_server_ip) State Change CS_REPORTING -> CS_DESTROY
2017-02-20 15:51:01.441054 [DEBUG] switch_core_session.c:1646 Session 16056 (sofia/external/1031@freeswitch_server_ip) Locked, Waiting on external entities
2017-02-20 15:51:01.441054 [NOTICE] switch_core_session.c:1664 Session 16056 (sofia/external/1031@freeswitch_server_ip) Ended
2017-02-20 15:51:01.441054 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/1031@freeswitch_server_ip [CS_DESTROY]
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:630 (sofia/external/1031@freeswitch_server_ip) Running State Change CS_DESTROY
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:640 (sofia/external/1031@freeswitch_server_ip) State DESTROY
2017-02-20 15:51:01.441054 [DEBUG] mod_sofia.c:341 sofia/external/1031@freeswitch_server_ip SOFIA DESTROY
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:111 sofia/external/1031@freeswitch_server_ip Standard DESTROY
2017-02-20 15:51:01.441054 [DEBUG] switch_core_state_machine.c:640 (sofia/external/1031@freeswitch_server_ip) State DESTROY going to sleep