2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/3000@10.30.80.37) Running State Change CS_NEW
2016-05-23 11:13:40.149854 [DEBUG] sofia.c:9376 sofia/internal/3000@10.30.80.37 receiving invite from 10.2.250.73:63361 version: 1.6.8 git 99de0ad 2016-05-05 15:38:32Z 64bit
2016-05-23 11:13:40.149854 [DEBUG] sofia.c:9488 IP 10.2.250.73 Approved by acl "domains[]". Access Granted.
2016-05-23 11:13:40.149854 [DEBUG] sofia.c:6858 Channel sofia/internal/3000@10.30.80.37 entering state [received][100]
2016-05-23 11:13:40.149854 [DEBUG] sofia.c:6868 Remote SDP:
v=0
o=- 13108472028284007 1 IN IP4 10.2.251.5
s=X-Lite release 4.9.3 stamp 79961
c=IN IP4 10.2.251.5
t=0 0
m=audio 51114 RTP/AVP 9 8 120 0 84 101
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:84 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2016-05-23 11:13:40.149854 [DEBUG] sofia.c:7227 (sofia/internal/3000@10.30.80.37) State Change CS_NEW -> CS_INIT
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/3000@10.30.80.37) State NEW
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/3000@10.30.80.37) Running State Change CS_INIT
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/3000@10.30.80.37) State INIT
2016-05-23 11:13:40.149854 [DEBUG] mod_sofia.c:89 sofia/internal/3000@10.30.80.37 SOFIA INIT
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:40 sofia/internal/3000@10.30.80.37 Standard INIT
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/3000@10.30.80.37) State Change CS_INIT -> CS_ROUTING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/3000@10.30.80.37) State INIT going to sleep
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/3000@10.30.80.37) Running State Change CS_ROUTING
2016-05-23 11:13:40.149854 [DEBUG] switch_channel.c:2249 (sofia/internal/3000@10.30.80.37) Callstate Change DOWN -> RINGING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/3000@10.30.80.37) State ROUTING
2016-05-23 11:13:40.149854 [DEBUG] mod_sofia.c:142 sofia/internal/3000@10.30.80.37 SOFIA ROUTING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:236 sofia/internal/3000@10.30.80.37 Standard ROUTING
2016-05-23 11:13:40.149854 [INFO] mod_dialplan_xml.c:637 Processing 3000 <3000>->2000 in context public
Dialplan: sofia/internal/3000@10.30.80.37 parsing [public->unloop] continue=false
Dialplan: sofia/internal/3000@10.30.80.37 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/3000@10.30.80.37 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/3000@10.30.80.37 parsing [public->outside_call] continue=true
Dialplan: sofia/internal/3000@10.30.80.37 Absolute Condition [outside_call]
Dialplan: sofia/internal/3000@10.30.80.37 Action set(outside_call=true)
Dialplan: sofia/internal/3000@10.30.80.37 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/3000@10.30.80.37 parsing [public->call_debug] continue=true
Dialplan: sofia/internal/3000@10.30.80.37 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/3000@10.30.80.37 parsing [public->public_extensions] continue=false
Dialplan: sofia/internal/3000@10.30.80.37 Regex (FAIL) [public_extensions] destination_number(2000) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/3000@10.30.80.37 parsing [public->public_did] continue=false
Dialplan: sofia/internal/3000@10.30.80.37 Regex (FAIL) [public_did] destination_number(2000) =~ /^(5551212)$/ break=on-false
Dialplan: sofia/internal/3000@10.30.80.37 parsing [public->Calls from Charlotte Freeswitch-TEST] continue=false
Dialplan: sofia/internal/3000@10.30.80.37 Regex (FAIL) [Calls from Charlotte Freeswitch-TEST] destination_number(2000) =~ /^(300[0-9])$/ break=on-false
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/3000@10.30.80.37) State Change CS_ROUTING -> CS_EXECUTE
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/3000@10.30.80.37) State ROUTING going to sleep
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/3000@10.30.80.37) Running State Change CS_EXECUTE
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/3000@10.30.80.37) State EXECUTE
2016-05-23 11:13:40.149854 [DEBUG] mod_sofia.c:197 sofia/internal/3000@10.30.80.37 SOFIA EXECUTE
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:328 sofia/internal/3000@10.30.80.37 Standard EXECUTE
EXECUTE sofia/internal/3000@10.30.80.37 set(outside_call=true)
2016-05-23 11:13:40.149854 [DEBUG] mod_dptools.c:1519 SET sofia/internal/3000@10.30.80.37 [outside_call]=[true]
EXECUTE sofia/internal/3000@10.30.80.37 export(RFC2822_DATE=Mon, 23 May 2016 11:13:40 +0100)
2016-05-23 11:13:40.149854 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 23 May 2016 11:13:40 +0100]
2016-05-23 11:13:40.149854 [NOTICE] switch_core_state_machine.c:385 sofia/internal/3000@10.30.80.37 has executed the last dialplan instruction, hanging up.
2016-05-23 11:13:40.149854 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/3000@10.30.80.37 [CS_EXECUTE] [NORMAL_CLEARING]
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/3000@10.30.80.37) State EXECUTE going to sleep
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/3000@10.30.80.37) Running State Change CS_HANGUP
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/3000@10.30.80.37) Callstate Change RINGING -> HANGUP
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/3000@10.30.80.37) State HANGUP
2016-05-23 11:13:40.149854 [DEBUG] mod_sofia.c:437 Channel sofia/internal/3000@10.30.80.37 hanging up, cause: NORMAL_CLEARING
2016-05-23 11:13:40.149854 [DEBUG] mod_sofia.c:574 Responding to INVITE with: 480
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:60 sofia/internal/3000@10.30.80.37 Standard HANGUP, cause: NORMAL_CLEARING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/3000@10.30.80.37) State HANGUP going to sleep
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/3000@10.30.80.37) State Change CS_HANGUP -> CS_REPORTING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/3000@10.30.80.37) Running State Change CS_REPORTING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/3000@10.30.80.37) State REPORTING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:174 sofia/internal/3000@10.30.80.37 Standard REPORTING, cause: NORMAL_CLEARING
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/3000@10.30.80.37) State REPORTING going to sleep
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/3000@10.30.80.37) State Change CS_REPORTING -> CS_DESTROY
2016-05-23 11:13:40.149854 [DEBUG] switch_core_session.c:1646 Session 165 (sofia/internal/3000@10.30.80.37) Locked, Waiting on external entities
2016-05-23 11:13:40.149854 [NOTICE] switch_core_session.c:1664 Session 165 (sofia/internal/3000@10.30.80.37) Ended
2016-05-23 11:13:40.149854 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/3000@10.30.80.37 [CS_DESTROY]
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/3000@10.30.80.37) Running State Change CS_DESTROY
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/3000@10.30.80.37) State DESTROY
2016-05-23 11:13:40.149854 [DEBUG] mod_sofia.c:342 sofia/internal/3000@10.30.80.37 SOFIA DESTROY
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:181 sofia/internal/3000@10.30.80.37 Standard DESTROY
2016-05-23 11:13:40.149854 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/3000@10.30.80.37) State DESTROY going to sleep
ldqwXM3sG1amFeodpCxrtZwA22Rh7hfNJLU6uGSmNx1OgcnND9pzBdPlZic4JWEzPrDnlC/bXSFARXX+6SXKsPmJDQMPM82qS/3a
gHX/PZUFb8NniXgPXLPpqIUKeHqmWN/jgt6uxQjtDbrqpzgPprwEUiM8HFrxpip3YO3TKiyWvLK/JFKwEQvQ2b1MVRSlBrew9GkH
oI4mhnVTjnEQivGmQ7ROMm+vAPuOqrJsXz1BIbXzipj7scTHg1t7KNknR6Ts5FvkdMXwvWUgSYhNLK/NXUpYcmk4G1bBVvxe9xKE
AubYowHyCmlFUOT0HJbzjSaQfsBMVAI//S1gasM35vGv97NDfwL0/bwcqxM8fQyYyyDCbv9uM/lcxpk8YygTxjQvwcapdsSEljR3
xNCEZYF3iP7gYzOHJYA4rWynVHba8jTKW7pCJKDVtHx5iXnjoj4KoCqe+rG5eDvze9xy3/hjwlYQw2GptKQJSQbPwGhxj8sZq1z/
KWQvkTlN+lF55pBe1J9muxdWZLqQFibGp9rKzR88XHx5Ek90YbbphrWKGKwVquu2eACTVNopQ6djh2xms43EnCZz0yxqcPqyMM7S
zWFtalSnLXwZGpyLDVxpY474320SEW5V5Yw4L2wfIXPxwko49YUBIi7kfRBQSr5qSAl0bPzNYohNb+gdIJnrlRPmdfvYlMUEoSUp
7SSpBSBcHwgmXLSY4psN9ZZ5hazZPMbKup9jVZuVXQ/0W58Kb6qx066xvANEz0HJhHGr40DYS1avU4+H+T0jAptG1/6kQK6DC5q/
YjLc1xmwb/8tzY8e+jE7NCkNmauwmx2WsIw8w+6y1mvio67N4BH9TQUAWCQ5hUd3IgrtryTm1o/nf8Y+bgqi1+SNO1dhh+pvfHpS
i9i6G+wcflD0oxPm9V/ZNY6NV9AsUrK1ISxx4DGhI2RIyMKYMtZBdDMFvSu808UVdl3lXhJvpDNKMTwP1wbDfiYvdayZc9o8nzcM
gNWFdY4fkI5vAtMdXEfo6LEmZZdeFuwZdXmSHC+zVSHilgSKSpO0ZVUHoIMPPD+KwEWO8ajGoO+F6vCxw/IhhW+RCRV5hLqpVFBo
2u5FVzsRdV8Br1vEXgFU/3J3rk9WreOGs5kB+f+SvWJvEU5jaefI0AERtZ4/D8+1r8R0tt2QdbUN8a9rU38/wQlvqKkmA0mulf0y
ZhFkY/WoSkjmxLs0i6jIs++9XcDVSN2n6cB6x+bDFfC0LPCmTIW8kc4v6XA8OczNpqbaGEl8EHlysaiEaZVXqAdG0DbhXEXoXsAi
c2gHVh04YTj5hAbrsDrre8zvlaw3hCvZaKgogFRIdlbbOHPPG+zFU461HQeiJKZjxiv2BkPhjXd16zgZRbpjyaFI+PXtIHMG//l+
ueqUScC1VdoXdTyo8aV9KUnbiHgx7vvFwagcuOaEAVGMUZF7AljOBswfO657hPz4iQhV+XYSpp35r1RprJ+QLaVwHgXmEm/FTyEO
uCb4pXTX0hEeDNnJjN2ZGzMGAHO0MWCAihoZR47aJ9rVMsG1APK6crHhIp2d4GrE/dluiil0VjVEIhjXrYT69mzrM22HlkkvQ7AT
jFxuRoZ3QRyxoSmGKHQRecuQSSFZvh3qerZ2TyJSxMB63saa5wUBgEESqtj3le7nR65ndm/0ScoxeVZK4pfJBkVapmU8NTwcs8Pz
4XKu1xd5dF7Lj7yXZaXr0tDgm9zNW1lqofQvGYVgR3LaG/0RrohDR6vs/PEjIxVpSuipgiqDWyXr/WQZKkfK/j60VJaypvX6a5nF
OnT6q/mhBJxXhwgTf5xzCoiDQFstxCAx166UDLlFav8/QZs3NSxN/QyDvIdkp54kQHdSXhU7eqpo1q8szczaW3NlPOZurnHteaFB
mDXU8xpAl+tEnymTUEmqx0/UTWUnAoMx21B9NV766uqdEPiCQGeo5yRI0LCyb4mO39ueYYO7EH7yoZVtGEPqsl0WNlE0TjuPz8rf
uojV4DXCuYhZV1xMwfW9WJNgsHO/Ve8KLVOzCXT8f/AxVns3HYKxAKF9aYu1s0PDfD1Xp7tWMGJ9Msta6+KT8E6+3qXKosV8Y0HN
q57UZPECRPBOpN5pt2VKsd+0IPTiCX41KY1ObxEJW+lOiu4QXbyWLwA5b2pSvtoQyjwKMLBIMAj+Keq6ssK60DA//PmrH82Vsll0
17zcmf4mg2wDsSlO/4XNRrhZIxt1nAGo59UJQlTyO9PdhbJt3Bh3IG+3FU50xyqlxcrryfmKPDohkH7ci4H/dg9cZwsyDmdwlv10
4OqYjPP64HpR0T6OdrCyPMTiQ7GqPiOzevCYZaVTM1CjPhHvipBxpBL/1sLVHd3BT+2Xp3hMzjbL0D4s9oBrsFHO8/UdBU2mwrlS
rlQG/wB1hn/+XydiN8qBnLsFGRlnwsbAxEJKq5pQ7bltk3t+rgVUL0ETOED0c0NzX07OfNd4OGoygZSigVXbIqcQcUXuiwkQQTX8
xhlzl5xK4U7XttbhFjTzs4NI7+JDFIG7AtxlhOfh5FWesT5iJH3kmPGKWvzSYZE3exm5WOacttTT6LHjEARePqrcbibldILFIG79
QEmh2F2FsBORKF0yrNdAzr2aULu+ZJJu8vqAkrybjr2EqhJNC5a719FGoQcq1pOJjyNbWgLSnvuwV6U2W98uUiQw484JzL8ACaXy
jbOp3vRg02meGfDTZnXiQWeLKzOUuctzI2u8rVCB5iH/DcHaIO2jtKq0DHpXQkExXzmOajeP/GkJxi+RrFj4FG6xiRKxWfmR8+ST
fa0DaDMRIA34nHYrokOP7d5eSHl/IaW8NAXUnwxpoDgyBu4oAKp+wV6MEVY/3n3rLanhcXjHz1ML469vgWYvnwrp5McaLSl2Mww2
DlO9rcsWFqprBG7Iy31VeJs9I01/1tbCbkIJrsOVoQ+licHPzU3wDheLO5+3C0kciSpfpcPlTs9x/4J7Ix0HyDhwdH9Dhdhr+Dka
x7RM5saKkKD7mPQXqsliKrj9qNvDPwjwWGMRPkvkuvNyOgIZqYnTVu2ybQlZ9/B1VZvCbWMfZnfJMMVog2C4NUIJf3edzcIHzEwh
Y1el2iA14WZONsMP+qEtX5sEms1x7ajfJgcPyRxnS0UTRULEycxqho1AF96gOiKLNRaYQ6Z+8GKfINcvRcH3dZnyvYBlDNDUQb5K
sDD8oyGHeltr2+CFqWe5RWzTPCr44VRAy39v+gI0RTV1bf5B8lEkFKQSFWPyWJcIsyCLiVoT9bn3KYupCRVWQy14IgtFAyFZCkNB
MP6vyau72ZpXfer3JlKQR9kackOg1WWbmpgRxRb3l1A1aDO0OERSBoSZLnQ1+/Ff5mFUgMHaiEf22MYaBX79DJkXr0kl+V+PTeTv
eIF8oWy4WvfMYLolD1nsiSOWar+TIyURiaAn0VzzhxrX5c7MGySxptk77IjXjjrfGLfnhpOI9E0Ar9f9UGx0PHmQUx+m5c3/7e1r
7fr6b/n4dvn6+vcNn4jZT5qgTIVK8a12KWJjy9faIGeAul9sX14ZAnsacGdUEK5MrGf34Jr0Jpfk9hvTi/0gQwWKNBBEIAh1tTia
QLqKwOmT9KnfE/ocDRHQ6QakBXIWsYK0YsuQv8tSt1USl1tGcSbdXnt5XjuiTacB9qt+YeE01XtIsaNSVL8kKyLsLpTMaa53yfhT
cBb359cQgPjbjejHSzLvMuMJXFqe85sKFMtozncwYVuR+yBriVhTa9im53mZyDWAwy3yfcvsFKS2QZo+5P36TkUdKBFmxdWheaGX
jja1rz3jlgiH+mqQLS59uTmRWruLeUgowSltwF4dc3aW931+XfjFC11pX6TiybmYqEkyIaMSEeourbP7W06JGz9q/41RUNab4D+f
VlUvHrs1n2eJJa8sDo5G7gxGvGTXw+tbhPuVQe10Q10mZxRVi4+oogun1D2eVBNnEYez3170DS1+LSSZfOs5+7wJreO6X+6/BWLS
2XHvkcf+2weXy32JzenTDGl20OTy+URdyQb9V1P0MVIJrpPBXR8psa8kEa815rTStzSo3w94x2t9071KleWnlVnAWsoTDepNzKJa
28oRMGniD7+nDeT9xjqRh1G/QMF4TX8iqkuhKzpreJuKQlKToSerqAIoTZ8a6lK9oXK5K6v+La2A1cO6RzlKDQuXCQZRuhi0KH3j
N8Cp4+hY33osVbDdrBjz13kV99PcJj+vznGgHDmyc4fxznVlX+OSHP0o5eTWtBIeCqb2ur3v3lFR2jOxAdAU2kY+rjIGZN7/J5pN
SBl1YUg9AuUkTqU6ZQcM3gEbaCqiMp4BScAmBbkhgGNWWp2ppuYEhMuisxYF+Vv1UoSTifQzUhAP0XL26IW0rbqQm7SinH/jfVR5
0S2lWVt6I5evJFI/vPjdfoGrn4e3Eq/EsUn8YxBZM7uzD8mbhY/VaO38f3UlJkUVzxCnQBArrTp6j1VV/Ssdy6x4fZiGKhFkI7hF
trt3njP00FNdkJBiNSOtVh/MpqvbBEyLqANyk0WFQHHH5dPoqyYU5WYt27AWIc/rtB2a7mo9TgzADTCQ7r+HahBJRy2n//NbG1wq
vZJ4Pe/l2wQMx+fKBJjn+FOFeC0B/MCAwAkP+Jxgb2AIeqngQcaBzFej2RIQWdiCvEY/JMa2P6lZuRbwQx2GLeO6oGCpEldyi9o/
hrnx+LXexMLETCKN4l2zR9jHlYX8S2DHsXb18SW6ln4SuNOIjWfH6hLYSXDUmjh+k3Pa/YR47Q5eoWzraZu82etD4rkw7xb89E2Q
BMYlsdBVa8BWmEAZA3qCWQdnsQwNY/v5X6JoQU5J+3wnqjzsVKCi00UXWxByPweZ8wuKmqZ8g4HHP3Qpy9gUlJLhB+YmyDheRve8
+0QBHkWhGslK/3Tpksed/E0NuW+4713wILZhRNeYgdsOGr355DCzpidyk7Kj5Of2n2JkAquC4IF/fa7FSRMus5aUk+rsihFEaPfS
L0fLjOZ7NXtYzkHSLz0B4rwdxpN21TR6E2UwXdR1deyo9a5FIS20U1xJHjl7SFoMg8aVCBd8p/dQh3rlpAQ3lTyk3RlyrEqV9/+d
XrueR12mbH4G47aMJLGfxN9XXyhRQj4YKcPFrnXyBBRLu1QZL4/BSccA6PgL/7KzW5gGw77U9gAJHUpxKcXyPfjFEmuwxCnpf8Jd
AexmlJVCt2bWlU8jABBKvrIV1yUzAi7gueAKGSR/KlFjrvRsJ+/Ko0rmh4ivg7VFrdYlYAT83y0FwLLjHqblKabkAoNyC40ZsTr1
T0AC2+hsp4/uoUw1AA5VgclSqEyOCCQHDkelBVoqB2cM6aezOza7oAt1RsuX9s9Prd5OnPA0rbJEGcr7llmcufdw4jgMDF5cswRZ
i8xhyK1uV9HgZHPt3NHqELo22oVIBeOpSMVrC5yiR5b0vIgUwQPYS0wM1GpRizua6a5Yu4UcN/WR2JUdpAaUtVgWAd8V0WL0Fs1/
KxBZa0w8nV89ZzVI8IZo6MpS1jFrCtphZgUgGxSjXAeRbW/S/oh6hmSuB8F7KYSbZZQQCLNgWG8d39LKYfGWSa9GS4hTQNgmH31T
2K89oofUG2J2WZGRiAoEC2yGTrKi58IOjfh8Hy7ZURYx7oTj4wpK5OYdaLMwLN5M26Gtis8fJ4qlYaghhNoUcPOwNFJ68aud8kCq
P8Iz05SQTO1NaSsFqXtlfWrY6HtisfIl1lv2YqDrNNxukfP0/uQZbBVV/88Bvy4oEM/LPmzD3iyxZVqW0HoaWSlztz05QlsDnOI1
mxmJm0lQg0qp1retOXyKggADuxgNwPudflJ5yt7jIB4Q+4bjmDpibRExHrYzi0agF+46untxjaSRHC2Aq4RTH2dOvicAjoM=