freeswitch@internal> 2014-09-29 13:15:42.651108 [DEBUG] sofia.c:5798 IP 122.161.236.14 Approved by acl "domains[]". Access Granted. 2014-09-29 13:15:42.651108 [NOTICE] switch_channel.c:669 New Channel sofia/internal/1001@54.73.48.31 [b701f896-47da-11e4-8195-2ff352420a79] 2014-09-29 13:15:42.651108 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1001@54.73.48.31) Running State Change CS_NEW 2014-09-29 13:15:42.651108 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/1001@54.73.48.31) State NEW 2014-09-29 13:15:42.681163 [DEBUG] sofia.c:4153 Channel sofia/internal/1001@54.73.48.31 entering state [received][100] 2014-09-29 13:15:42.681163 [DEBUG] sofia.c:4164 Remote SDP: v=0 o=FreeSWITCH 1411980058 1411980059 IN IP4 122.161.236.14 s=FreeSWITCH c=IN IP4 122.161.236.14 t=0 0 m=audio 14034 RTP/AVP 98 0 3 8 9 99 100 101 13 a=rtpmap:98 L16/24000 a=rtpmap:99 G7221/16000 a=fmtp:99 bitrate=32000 a=rtpmap:100 G7221/32000 a=fmtp:100 bitrate=48000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [L16:98:24000:20]/[G7221:115:32000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [L16:98:24000:20]/[G7221:107:16000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [L16:98:24000:20]/[G722:9:8000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [L16:98:24000:20]/[PCMU:0:8000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [L16:98:24000:20]/[PCMA:8:8000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [L16:98:24000:20]/[GSM:3:8000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[G7221:115:32000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[G7221:107:16000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[G722:9:8000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20] 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:2354 Set Codec sofia/internal/1001@54.73.48.31 PCMU/8000 20 ms 160 samples 2014-09-29 13:15:42.681163 [DEBUG] sofia_glue.c:3524 Set 2833 dtmf send/recv payload to 101 2014-09-29 13:15:42.681163 [DEBUG] sofia.c:4310 (sofia/internal/1001@54.73.48.31) State Change CS_NEW -> CS_INIT 2014-09-29 13:15:42.681163 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1001@54.73.48.31 [BREAK] 2014-09-29 13:15:42.681163 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1001@54.73.48.31) Running State Change CS_INIT 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/1001@54.73.48.31) State INIT 2014-09-29 13:15:42.691364 [DEBUG] mod_sofia.c:83 sofia/internal/1001@54.73.48.31 SOFIA INIT 2014-09-29 13:15:42.691364 [DEBUG] mod_sofia.c:117 (sofia/internal/1001@54.73.48.31) State Change CS_INIT -> CS_ROUTING 2014-09-29 13:15:42.691364 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1001@54.73.48.31 [BREAK] 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/1001@54.73.48.31) State INIT going to sleep 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1001@54.73.48.31) Running State Change CS_ROUTING 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/1001@54.73.48.31) State ROUTING 2014-09-29 13:15:42.691364 [DEBUG] mod_sofia.c:140 sofia/internal/1001@54.73.48.31 SOFIA ROUTING 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:77 sofia/internal/1001@54.73.48.31 Standard ROUTING 2014-09-29 13:15:42.691364 [INFO] mod_dialplan_xml.c:418 Processing Manish Talwar->5000 in context public Dialplan: sofia/internal/1001@54.73.48.31 parsing [public->unloop] continue=false Dialplan: sofia/internal/1001@54.73.48.31 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/1001@54.73.48.31 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/1001@54.73.48.31 parsing [public->outside_call] continue=true Dialplan: sofia/internal/1001@54.73.48.31 Absolute Condition [outside_call] Dialplan: sofia/internal/1001@54.73.48.31 Action set(outside_call=true) Dialplan: sofia/internal/1001@54.73.48.31 parsing [public->call_debug] continue=true Dialplan: sofia/internal/1001@54.73.48.31 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/1001@54.73.48.31 parsing [public->public_extensions] continue=false Dialplan: sofia/internal/1001@54.73.48.31 Regex (FAIL) [public_extensions] destination_number(5000) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/1001@54.73.48.31 parsing [public->public_did] continue=false Dialplan: sofia/internal/1001@54.73.48.31 Regex (FAIL) [public_did] destination_number(5000) =~ /^(5551212)$/ break=on-false 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/1001@54.73.48.31) State Change CS_ROUTING -> CS_EXECUTE 2014-09-29 13:15:42.691364 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1001@54.73.48.31 [BREAK] 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/1001@54.73.48.31) State ROUTING going to sleep 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1001@54.73.48.31) Running State Change CS_EXECUTE 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/1001@54.73.48.31) State EXECUTE 2014-09-29 13:15:42.691364 [DEBUG] mod_sofia.c:226 sofia/internal/1001@54.73.48.31 SOFIA EXECUTE 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:157 sofia/internal/1001@54.73.48.31 Standard EXECUTE EXECUTE sofia/internal/1001@54.73.48.31 set(outside_call=true) 2014-09-29 13:15:42.691364 [DEBUG] mod_dptools.c:816 sofia/internal/1001@54.73.48.31 SET [outside_call]=[true] 2014-09-29 13:15:42.691364 [NOTICE] switch_core_state_machine.c:185 sofia/internal/1001@54.73.48.31 has executed the last dialplan instruction, hanging up. 2014-09-29 13:15:42.691364 [NOTICE] switch_core_state_machine.c:187 Hangup sofia/internal/1001@54.73.48.31 [CS_EXECUTE] [NORMAL_CLEARING] 2014-09-29 13:15:42.691364 [DEBUG] switch_channel.c:2102 Send signal sofia/internal/1001@54.73.48.31 [KILL] 2014-09-29 13:15:42.691364 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1001@54.73.48.31 [BREAK] 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/1001@54.73.48.31) State EXECUTE going to sleep 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1001@54.73.48.31) Running State Change CS_HANGUP 2014-09-29 13:15:42.691364 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1001@54.73.48.31) State HANGUP 2014-09-29 13:15:42.691364 [DEBUG] mod_sofia.c:414 Channel sofia/internal/1001@54.73.48.31 hanging up, cause: NORMAL_CLEARING 2014-09-29 13:15:42.701437 [DEBUG] mod_sofia.c:476 Responding to INVITE with: 480 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1001@54.73.48.31 Standard HANGUP, cause: NORMAL_CLEARING 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1001@54.73.48.31) State HANGUP going to sleep 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@54.73.48.31) State Change CS_HANGUP -> CS_REPORTING 2014-09-29 13:15:42.701437 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1001@54.73.48.31 [BREAK] 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1001@54.73.48.31) Running State Change CS_REPORTING 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:590 (sofia/internal/1001@54.73.48.31) State REPORTING 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1001@54.73.48.31 Standard REPORTING, cause: NORMAL_CLEARING 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:590 (sofia/internal/1001@54.73.48.31) State REPORTING going to sleep 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/1001@54.73.48.31) State Change CS_REPORTING -> CS_DESTROY 2014-09-29 13:15:42.701437 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1001@54.73.48.31 [BREAK] 2014-09-29 13:15:42.701437 [DEBUG] switch_core_session.c:1164 Session 1 (sofia/internal/1001@54.73.48.31) Locked, Waiting on external entities 2014-09-29 13:15:42.701437 [NOTICE] switch_core_session.c:1182 Session 1 (sofia/internal/1001@54.73.48.31) Ended 2014-09-29 13:15:42.701437 [NOTICE] switch_core_session.c:1184 Close Channel sofia/internal/1001@54.73.48.31 [CS_DESTROY] 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:428 (sofia/internal/1001@54.73.48.31) Running State Change CS_DESTROY 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1001@54.73.48.31) State DESTROY 2014-09-29 13:15:42.701437 [DEBUG] mod_sofia.c:341 sofia/internal/1001@54.73.48.31 SOFIA DESTROY 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@54.73.48.31 Standard DESTROY 2014-09-29 13:15:42.701437 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1001@54.73.48.31) State DESTROY going to sleep