freeswitch@FeeSwitch> 
2017-02-06 16:24:43.439496 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1022@192.168.1.230 [9fed08eb-4d12-4cd4-a4e6-de8077fad949]
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1022@192.168.1.230) Running State Change CS_NEW (Cur 1 Tot 1)
2017-02-06 16:24:43.439496 [DEBUG] sofia.c:9815 sofia/internal/1022@192.168.1.230 receiving invite from 192.168.1.39:29343 version: 1.6.14 git 9743872 2017-02-03 22:25:09Z 64bit
2017-02-06 16:24:43.439496 [DEBUG] sofia.c:9931 IP 192.168.1.39 Approved by acl "domains[]". Access Granted.
2017-02-06 16:24:43.439496 [DEBUG] sofia.c:7041 Channel sofia/internal/1022@192.168.1.230 entering state [received][100]
2017-02-06 16:24:43.439496 [DEBUG] sofia.c:7051 Remote SDP:
v=0
o=1022 8000 8000 IN IP4 192.168.1.39
s=SIP Call
c=IN IP4 192.168.1.39
t=0 0
m=audio 11876 RTP/AVP 0 8 101
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-06 16:24:43.439496 [DEBUG] sofia.c:7443 (sofia/internal/1022@192.168.1.230) State Change CS_NEW -> CS_INIT
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1022@192.168.1.230) State NEW
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1022@192.168.1.230) Running State Change CS_INIT (Cur 1 Tot 1)
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1022@192.168.1.230) State INIT
2017-02-06 16:24:43.439496 [DEBUG] mod_sofia.c:90 sofia/internal/1022@192.168.1.230 SOFIA INIT
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1022@192.168.1.230 Standard INIT
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1022@192.168.1.230) State Change CS_INIT -> CS_ROUTING
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1022@192.168.1.230) State INIT going to sleep
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1022@192.168.1.230) Running State Change CS_ROUTING (Cur 1 Tot 1)
2017-02-06 16:24:43.439496 [DEBUG] switch_channel.c:2249 (sofia/internal/1022@192.168.1.230) Callstate Change DOWN -> RINGING
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1022@192.168.1.230) State ROUTING
2017-02-06 16:24:43.439496 [DEBUG] mod_sofia.c:143 sofia/internal/1022@192.168.1.230 SOFIA ROUTING
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1022@192.168.1.230 Standard ROUTING
2017-02-06 16:24:43.439496 [INFO] mod_dialplan_xml.c:637 Processing Mohsen <1022>->1027 in context public
Dialplan: sofia/internal/1022@192.168.1.230 parsing [public->inbound.lua] continue=false
Dialplan: sofia/internal/1022@192.168.1.230 Regex (FAIL) [inbound.lua] destination_number(1027) =~ /^2020/ break=on-false
Dialplan: sofia/internal/1022@192.168.1.230 parsing [public->send caller to FIFO] continue=false
Dialplan: sofia/internal/1022@192.168.1.230 Regex (FAIL) [send caller to FIFO] destination_number(1027) =~ /^2021/ break=on-false
Dialplan: sofia/internal/1022@192.168.1.230 parsing [public->unloop] continue=false
Dialplan: sofia/internal/1022@192.168.1.230 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1022@192.168.1.230 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1022@192.168.1.230 parsing [public->outside_call] continue=true
Dialplan: sofia/internal/1022@192.168.1.230 Absolute Condition [outside_call]
Dialplan: sofia/internal/1022@192.168.1.230 Action set(outside_call=true) 
Dialplan: sofia/internal/1022@192.168.1.230 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/internal/1022@192.168.1.230 parsing [public->call_debug] continue=true
Dialplan: sofia/internal/1022@192.168.1.230 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1022@192.168.1.230 parsing [public->public_extensions] continue=false
Dialplan: sofia/internal/1022@192.168.1.230 Regex (FAIL) [public_extensions] destination_number(1027) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1022@192.168.1.230 parsing [public->public_did] continue=false
Dialplan: sofia/internal/1022@192.168.1.230 Regex (FAIL) [public_did] destination_number(1027) =~ /^(5551212)$/ break=on-false
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1022@192.168.1.230) State Change CS_ROUTING -> CS_EXECUTE
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1022@192.168.1.230) State ROUTING going to sleep
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1022@192.168.1.230) Running State Change CS_EXECUTE (Cur 1 Tot 1)
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1022@192.168.1.230) State EXECUTE
2017-02-06 16:24:43.439496 [DEBUG] mod_sofia.c:198 sofia/internal/1022@192.168.1.230 SOFIA EXECUTE
2017-02-06 16:24:43.439496 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1022@192.168.1.230 Standard EXECUTE
EXECUTE sofia/internal/1022@192.168.1.230 set(outside_call=true)
2017-02-06 16:24:43.439496 [DEBUG] mod_dptools.c:1527 SET sofia/internal/1022@192.168.1.230 [outside_call]=[true]
EXECUTE sofia/internal/1022@192.168.1.230 export(RFC2822_DATE=Mon, 06 Feb 2017 16:24:43 +0330)
2017-02-06 16:24:43.459492 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 06 Feb 2017 16:24:43 +0330]
2017-02-06 16:24:43.459492 [NOTICE] switch_core_state_machine.c:385 sofia/internal/1022@192.168.1.230 has executed the last dialplan instruction, hanging up.
2017-02-06 16:24:43.459492 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/1022@192.168.1.230 [CS_EXECUTE] [NORMAL_CLEARING]
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1022@192.168.1.230) State EXECUTE going to sleep
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1022@192.168.1.230) Running State Change CS_HANGUP (Cur 1 Tot 1)
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1022@192.168.1.230) Callstate Change RINGING -> HANGUP
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1022@192.168.1.230) State HANGUP
2017-02-06 16:24:43.459492 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1022@192.168.1.230 hanging up, cause: NORMAL_CLEARING
2017-02-06 16:24:43.459492 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1022@192.168.1.230 Standard HANGUP, cause: NORMAL_CLEARING
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1022@192.168.1.230) State HANGUP going to sleep
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1022@192.168.1.230) State Change CS_HANGUP -> CS_REPORTING
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1022@192.168.1.230) Running State Change CS_REPORTING (Cur 1 Tot 1)
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1022@192.168.1.230) State REPORTING
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1022@192.168.1.230 Standard REPORTING, cause: NORMAL_CLEARING
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1022@192.168.1.230) State REPORTING going to sleep
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1022@192.168.1.230) State Change CS_REPORTING -> CS_DESTROY
2017-02-06 16:24:43.459492 [DEBUG] switch_core_session.c:1647 Session 1 (sofia/internal/1022@192.168.1.230) Locked, Waiting on external entities
2017-02-06 16:24:43.459492 [NOTICE] switch_core_session.c:1665 Session 1 (sofia/internal/1022@192.168.1.230) Ended
2017-02-06 16:24:43.459492 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/1022@192.168.1.230 [CS_DESTROY]
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1022@192.168.1.230) Running State Change CS_DESTROY (Cur 0 Tot 1)
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1022@192.168.1.230) State DESTROY
2017-02-06 16:24:43.459492 [DEBUG] mod_sofia.c:343 sofia/internal/1022@192.168.1.230 SOFIA DESTROY
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1022@192.168.1.230 Standard DESTROY
2017-02-06 16:24:43.459492 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1022@192.168.1.230) State DESTROY going to sleep
freeswitch@FeeSwitch>