2013-12-26 13:55:11.789665 [NOTICE] switch_channel.c:1030 New Channel sofia/external/972527699999@178.79.147.47 [d9eea688-202e-495b-8a74-74136321cca6]
2013-12-26 13:55:11.789665 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.789665 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.789665 [DEBUG] switch_core_state_machine.c:416 (sofia/external/972527699999@178.79.147.47) Running State Change CS_NEW
2013-12-26 13:55:11.789665 [DEBUG] switch_core_state_machine.c:434 (sofia/external/972527699999@178.79.147.47) State NEW
2013-12-26 13:55:11.809863 [DEBUG] sofia.c:5775 Channel sofia/external/972527699999@178.79.147.47 entering state [received][100]
2013-12-26 13:55:11.809863 [DEBUG] sofia.c:5784 Remote SDP:
v=0
o=FreeSWITCH 1388031445 1388031446 IN IP4 178.79.147.47
s=FreeSWITCH
c=IN IP4 178.79.147.47
t=0 0
m=audio 27466 RTP/AVP 0 8 9 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2013-12-26 13:55:11.809863 [DEBUG] sofia.c:5997 (sofia/external/972527699999@178.79.147.47) State Change CS_NEW -> CS_INIT
2013-12-26 13:55:11.809863 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:416 (sofia/external/972527699999@178.79.147.47) Running State Change CS_INIT
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:455 (sofia/external/972527699999@178.79.147.47) State INIT
2013-12-26 13:55:11.809863 [DEBUG] mod_sofia.c:87 sofia/external/972527699999@178.79.147.47 SOFIA INIT
2013-12-26 13:55:11.809863 [DEBUG] mod_sofia.c:114 (sofia/external/972527699999@178.79.147.47) State Change CS_INIT -> CS_ROUTING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:455 (sofia/external/972527699999@178.79.147.47) State INIT going to sleep
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:416 (sofia/external/972527699999@178.79.147.47) Running State Change CS_ROUTING
2013-12-26 13:55:11.809863 [DEBUG] switch_channel.c:2116 (sofia/external/972527699999@178.79.147.47) Callstate Change DOWN -> RINGING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:471 (sofia/external/972527699999@178.79.147.47) State ROUTING
2013-12-26 13:55:11.809863 [DEBUG] mod_sofia.c:137 sofia/external/972527699999@178.79.147.47 SOFIA ROUTING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:117 sofia/external/972527699999@178.79.147.47 Standard ROUTING
2013-12-26 13:55:11.809863 [INFO] mod_dialplan_xml.c:558 Processing 972527699999 <972527699999>->97223721565 in context public
Dialplan: sofia/external/972527699999@178.79.147.47 parsing [public->unloop] continue=false
Dialplan: sofia/external/972527699999@178.79.147.47 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/972527699999@178.79.147.47 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/972527699999@178.79.147.47 parsing [public->outside_call] continue=true
Dialplan: sofia/external/972527699999@178.79.147.47 Absolute Condition [outside_call]
Dialplan: sofia/external/972527699999@178.79.147.47 Action set(outside_call=true) 
Dialplan: sofia/external/972527699999@178.79.147.47 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/external/972527699999@178.79.147.47 parsing [public->call_debug] continue=true
Dialplan: sofia/external/972527699999@178.79.147.47 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/external/972527699999@178.79.147.47 parsing [public->public_extensions] continue=false
Dialplan: sofia/external/972527699999@178.79.147.47 Regex (FAIL) [public_extensions] destination_number(97223721565) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/external/972527699999@178.79.147.47 parsing [public->public_did] continue=false
Dialplan: sofia/external/972527699999@178.79.147.47 Regex (FAIL) [public_did] destination_number(97223721565) =~ /^(5551212)$/ break=on-false
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:167 (sofia/external/972527699999@178.79.147.47) State Change CS_ROUTING -> CS_EXECUTE
2013-12-26 13:55:11.809863 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:471 (sofia/external/972527699999@178.79.147.47) State ROUTING going to sleep
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:416 (sofia/external/972527699999@178.79.147.47) Running State Change CS_EXECUTE
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:478 (sofia/external/972527699999@178.79.147.47) State EXECUTE
2013-12-26 13:55:11.809863 [DEBUG] mod_sofia.c:230 sofia/external/972527699999@178.79.147.47 SOFIA EXECUTE
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:209 sofia/external/972527699999@178.79.147.47 Standard EXECUTE
EXECUTE sofia/external/972527699999@178.79.147.47 set(outside_call=true)
2013-12-26 13:55:11.809863 [DEBUG] mod_dptools.c:1373 sofia/external/972527699999@178.79.147.47 SET [outside_call]=[true]
EXECUTE sofia/external/972527699999@178.79.147.47 export(RFC2822_DATE=Thu, 26 Dec 2013 13:55:11 Jerusalem Standard Time)
2013-12-26 13:55:11.809863 [DEBUG] switch_channel.c:1222 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 26 Dec 2013 13:55:11 Jerusalem Standard Time]
2013-12-26 13:55:11.809863 [NOTICE] switch_core_state_machine.c:262 sofia/external/972527699999@178.79.147.47 has executed the last dialplan instruction, hanging up.
2013-12-26 13:55:11.809863 [NOTICE] switch_core_state_machine.c:264 Hangup sofia/external/972527699999@178.79.147.47 [CS_EXECUTE] [NORMAL_CLEARING]
2013-12-26 13:55:11.809863 [DEBUG] switch_channel.c:3135 Send signal sofia/external/972527699999@178.79.147.47 [KILL]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:478 (sofia/external/972527699999@178.79.147.47) State EXECUTE going to sleep
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:416 (sofia/external/972527699999@178.79.147.47) Running State Change CS_HANGUP
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:679 (sofia/external/972527699999@178.79.147.47) State HANGUP
2013-12-26 13:55:11.809863 [DEBUG] mod_sofia.c:463 Channel sofia/external/972527699999@178.79.147.47 hanging up, cause: NORMAL_CLEARING
2013-12-26 13:55:11.809863 [DEBUG] mod_sofia.c:597 Responding to INVITE with: 480
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:48 sofia/external/972527699999@178.79.147.47 Standard HANGUP, cause: NORMAL_CLEARING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:679 (sofia/external/972527699999@178.79.147.47) State HANGUP going to sleep
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:692 (sofia/external/972527699999@178.79.147.47) Callstate Change RINGING -> HANGUP
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:447 (sofia/external/972527699999@178.79.147.47) State Change CS_HANGUP -> CS_REPORTING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:416 (sofia/external/972527699999@178.79.147.47) Running State Change CS_REPORTING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:764 (sofia/external/972527699999@178.79.147.47) State REPORTING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:92 sofia/external/972527699999@178.79.147.47 Standard REPORTING, cause: NORMAL_CLEARING
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:764 (sofia/external/972527699999@178.79.147.47) State REPORTING going to sleep
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:441 (sofia/external/972527699999@178.79.147.47) State Change CS_REPORTING -> CS_DESTROY
2013-12-26 13:55:11.809863 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/972527699999@178.79.147.47 [BREAK]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_session.c:1549 Session 3 (sofia/external/972527699999@178.79.147.47) Locked, Waiting on external entities
2013-12-26 13:55:11.809863 [NOTICE] switch_core_session.c:1567 Session 3 (sofia/external/972527699999@178.79.147.47) Ended
2013-12-26 13:55:11.809863 [NOTICE] switch_core_session.c:1571 Close Channel sofia/external/972527699999@178.79.147.47 [CS_DESTROY]
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:566 (sofia/external/972527699999@178.79.147.47) Callstate Change HANGUP -> DOWN
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:569 (sofia/external/972527699999@178.79.147.47) Running State Change CS_DESTROY
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:579 (sofia/external/972527699999@178.79.147.47) State DESTROY
2013-12-26 13:55:11.809863 [DEBUG] mod_sofia.c:373 sofia/external/972527699999@178.79.147.47 SOFIA DESTROY
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:99 sofia/external/972527699999@178.79.147.47 Standard DESTROY
2013-12-26 13:55:11.809863 [DEBUG] switch_core_state_machine.c:579 (sofia/external/972527699999@178.79.147.47) State DESTROY going to sleep
2013-12-26 13:55:14.909574 [DEBUG] sofia_reg.c:2224 Changing expire time to 79 by request of proxy sip:callcentric.com