bg1 = #ffffff bg2 = #e7ebf1 192.168.0.102 [192.168.0.102 Domain Selector [ESC]] [Logout sansoft@192.168.0.102] Home Accounts Dialplan Apps Status Advanced Log Viewer Filter Show Line Numbers Sort Descending Display KB Displaying the last 32,768 of 962,849 bytes. _state_machine.c:166 sofia/internal/1111@192.168.0.102 Standard ROUTING d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [INFO] mod_dialplan_xml.c:635 Processing 1111 <1111>->9892504286 in context 192.168.0.102 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 parsing [192.168.0.102->user_exists] continue=true d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Absolute Condition [user_exists] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(user_exists=false) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [user_exists]=[false] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 parsing [192.168.0.102->call-direction] continue=true d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 ANTI-Action set(call_direction=local) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [call-direction] ${user_exists}(false) =~ /^false$/ break=on-false d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [call-direction] destination_number(9892504286) =~ /^\d{7,20}$/ break=on-false d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(call_direction=outbound) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 parsing [192.168.0.102->variables] continue=true d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Absolute Condition [variables] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action export(origination_callee_id_name=${destination_number}) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 parsing [192.168.0.102->user_record] continue=true d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Absolute Condition [user_record] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(user_record=) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [user_record]=[UNDEF] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(from_user_exists=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [from_user_exists]=[true] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(from_user_record=all) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [from_user_record]=[all] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [user_record] ${from_user_record}(all) =~ /^all$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(record_session=true) INLINE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(record_session=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [record_session]=[true] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^inbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^outbound$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^local$/ break=never d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [user_record] ${record_session}(true) =~ /^true$/ break=on-false d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(api_on_answer=uuid_record ${uuid} start ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext}) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 parsing [192.168.0.102->redial] continue=true d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (FAIL) [redial] destination_number(9892504286) =~ /^(redial|\*870)$/ break=on-true d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Absolute Condition [redial] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 parsing [192.168.0.102->DinStar.d1011] continue=false d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Regex (PASS) [DinStar.d1011] destination_number(9892504286) =~ /^(\d{10,11})$/ break=on-false d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(sip_h_X-accountcode=${accountcode}) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(sip_h_X-Tag=) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(call_direction=outbound) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(hangup_after_bridge=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(effective_caller_id_name=${outbound_caller_id_name}) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(effective_caller_id_number=${outbound_caller_id_number}) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(inherit_codec=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(ignore_display_updates=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(callee_id_number=9892504286) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action set(continue_on_fail=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c Dialplan: sofia/internal/1111@192.168.0.102 Action bridge(sofia/gateway/8b496174-9b9e-4a21-91bf-d89e62aeb879/9892504286) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1111@192.168.0.102) State Change CS_ROUTING -> CS_EXECUTE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/1111@192.168.0.102 [BREAK] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/1111@192.168.0.102) State ROUTING going to sleep d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1111@192.168.0.102) Running State Change CS_EXECUTE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/1111@192.168.0.102) State EXECUTE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_sofia.c:178 sofia/internal/1111@192.168.0.102 SOFIA EXECUTE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1111@192.168.0.102 Standard EXECUTE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(call_direction=local) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [call_direction]=[local] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(call_direction=outbound) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [call_direction]=[outbound] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 export(origination_callee_id_name=9892504286) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [origination_callee_id_name]=[9892504286] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(RFC2822_DATE=Wed, 24 Aug 2016 10:39:14 India Standard Time) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [RFC2822_DATE]=[Wed, 24 Aug 2016 10:39:14 India Standard Time] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(api_on_answer=uuid_record d0da2ec7-b841-4ae5-a5ca-3c2020d8752c start C:/Program Files/FreeSWITCH/recordings/192.168.0.102/archive/2016/Aug/24/d0da2ec7-b841-4ae5-a5ca-3c2020d8752c.wav) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [api_on_answer]=[uuid_record d0da2ec7-b841-4ae5-a5ca-3c2020d8752c start C:/Program Files/FreeSWITCH/recordings/192.168.0.102/archive/2016/Aug/24/d0da2ec7-b841-4ae5-a5ca-3c2020d8752c.wav] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 hash(insert/192.168.0.102-last_dial/1111/9892504286) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(sip_h_X-accountcode=192.168.0.102) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [sip_h_X-accountcode]=[192.168.0.102] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(sip_h_X-Tag=) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [sip_h_X-Tag]=[UNDEF] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(call_direction=outbound) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [call_direction]=[outbound] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(hangup_after_bridge=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.607421 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [hangup_after_bridge]=[true] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(effective_caller_id_name=) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [effective_caller_id_name]=[UNDEF] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(effective_caller_id_number=) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [effective_caller_id_number]=[UNDEF] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(inherit_codec=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [inherit_codec]=[true] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(ignore_display_updates=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [ignore_display_updates]=[true] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(callee_id_number=9892504286) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [callee_id_number]=[9892504286] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 set(continue_on_fail=true) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] mod_dptools.c:1435 sofia/internal/1111@192.168.0.102 SET [continue_on_fail]=[true] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c EXECUTE sofia/internal/1111@192.168.0.102 bridge(sofia/gateway/8b496174-9b9e-4a21-91bf-d89e62aeb879/9892504286) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] switch_channel.c:1201 sofia/internal/1111@192.168.0.102 EXPORTING[export_vars] [domain_name]=[192.168.0.102] to event d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] switch_channel.c:1201 sofia/internal/1111@192.168.0.102 EXPORTING[export_vars] [origination_callee_id_name]=[9892504286] to event d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.626953 [DEBUG] switch_ivr_originate.c:2103 Parsing global variables 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [NOTICE] switch_channel.c:1055 New Channel sofia/external/9892504286 [6197bbfc-c70f-4983-adad-08be37fe9a15] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] mod_sofia.c:4636 (sofia/external/9892504286) State Change CS_NEW -> CS_INIT 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:472 (sofia/external/9892504286) Running State Change CS_INIT 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:512 (sofia/external/9892504286) State INIT 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] mod_sofia.c:87 sofia/external/9892504286 SOFIA INIT 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] sofia_glue.c:1232 sofia/external/9892504286 sending invite version: 1.5.15b 32bit 6197bbfc-c70f-4983-adad-08be37fe9a15 Local SDP: 6197bbfc-c70f-4983-adad-08be37fe9a15 v=0 6197bbfc-c70f-4983-adad-08be37fe9a15 o=FreeSWITCH 1471988158 1471988159 IN IP4 192.168.0.102 6197bbfc-c70f-4983-adad-08be37fe9a15 s=FreeSWITCH 6197bbfc-c70f-4983-adad-08be37fe9a15 c=IN IP4 192.168.0.102 6197bbfc-c70f-4983-adad-08be37fe9a15 t=0 0 6197bbfc-c70f-4983-adad-08be37fe9a15 m=audio 27196 RTP/AVP 9 0 8 3 101 13 6197bbfc-c70f-4983-adad-08be37fe9a15 a=rtpmap:9 G722/8000 6197bbfc-c70f-4983-adad-08be37fe9a15 a=rtpmap:0 PCMU/8000 6197bbfc-c70f-4983-adad-08be37fe9a15 a=rtpmap:8 PCMA/8000 6197bbfc-c70f-4983-adad-08be37fe9a15 a=rtpmap:3 GSM/8000 6197bbfc-c70f-4983-adad-08be37fe9a15 a=rtpmap:101 telephone-event/8000 6197bbfc-c70f-4983-adad-08be37fe9a15 a=fmtp:101 0-16 6197bbfc-c70f-4983-adad-08be37fe9a15 a=ptime:20 6197bbfc-c70f-4983-adad-08be37fe9a15 a=sendrecv 6197bbfc-c70f-4983-adad-08be37fe9a15 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:40 sofia/external/9892504286 Standard INIT 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:48 (sofia/external/9892504286) State Change CS_INIT -> CS_ROUTING 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:512 (sofia/external/9892504286) State INIT going to sleep 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:472 (sofia/external/9892504286) Running State Change CS_ROUTING 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:528 (sofia/external/9892504286) State ROUTING 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] mod_sofia.c:123 sofia/external/9892504286 SOFIA ROUTING 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_ivr_originate.c:67 (sofia/external/9892504286) State Change CS_ROUTING -> CS_CONSUME_MEDIA 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:528 (sofia/external/9892504286) State ROUTING going to sleep 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:472 (sofia/external/9892504286) Running State Change CS_CONSUME_MEDIA 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:547 (sofia/external/9892504286) State CONSUME_MEDIA 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_state_machine.c:547 (sofia/external/9892504286) State CONSUME_MEDIA going to sleep 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.626953 [DEBUG] sofia.c:6614 Channel sofia/external/9892504286 entering state [calling][0] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] sofia.c:6614 Channel sofia/external/9892504286 entering state [proceeding][183] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] sofia.c:6624 Remote SDP: 6197bbfc-c70f-4983-adad-08be37fe9a15 v=0 6197bbfc-c70f-4983-adad-08be37fe9a15 o=Dinstar 17018365 17018366 IN IP4 192.168.0.212 6197bbfc-c70f-4983-adad-08be37fe9a15 s=- 6197bbfc-c70f-4983-adad-08be37fe9a15 c=IN IP4 192.168.0.212 6197bbfc-c70f-4983-adad-08be37fe9a15 t=0 0 6197bbfc-c70f-4983-adad-08be37fe9a15 m=audio 8008 RTP/AVP 0 6197bbfc-c70f-4983-adad-08be37fe9a15 a=rtpmap:0 PCMU/8000 6197bbfc-c70f-4983-adad-08be37fe9a15 a=ptime:20 6197bbfc-c70f-4983-adad-08be37fe9a15 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:3682 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:2473 Set Codec sofia/external/9892504286 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_codec.c:111 sofia/external/9892504286 Original read codec set to PCMU:0 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:3903 No 2833 in SDP. Disable 2833 dtmf and switch to INFO 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:5141 AUDIO RTP [sofia/external/9892504286] 192.168.0.102 port 27196 -> 192.168.0.212 port 8008 codec: 0 ms: 20 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_rtp.c:3548 Starting timer [soft] 160 bytes per 20ms 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/9892504286! d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_channel.c:3395 Send signal sofia/internal/1111@192.168.0.102 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_channel.c:3399 (sofia/external/9892504286) Callstate Change DOWN -> EARLY d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_ivr_originate.c:412 Setting codec string on sofia/internal/1111@192.168.0.102 to PCMU@8000h@20i d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [INFO] switch_ivr_originate.c:3529 Sending early media d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:5141 AUDIO RTP [sofia/internal/1111@192.168.0.102] 192.168.0.102 port 31776 -> 192.168.0.110 port 57382 codec: 9 ms: 20 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_rtp.c:3548 Starting timer [soft] 160 bytes per 20ms d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:5439 Set 2833 dtmf send payload to 101 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_core_media.c:5445 Set 2833 dtmf receive payload to 101 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] mod_sofia.c:2268 Ring SDP: d0da2ec7-b841-4ae5-a5ca-3c2020d8752c v=0 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c o=FreeSWITCH 1471983578 1471983579 IN IP4 192.168.0.102 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c s=FreeSWITCH d0da2ec7-b841-4ae5-a5ca-3c2020d8752c c=IN IP4 192.168.0.102 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c t=0 0 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c m=audio 31776 RTP/AVP 9 101 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=rtpmap:9 G722/8000 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=rtpmap:101 telephone-event/8000 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=fmtp:101 0-16 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=ptime:20 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=sendrecv d0da2ec7-b841-4ae5-a5ca-3c2020d8752c d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [NOTICE] mod_sofia.c:2271 Pre-Answer sofia/internal/1111@192.168.0.102! d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_channel.c:3399 (sofia/internal/1111@192.168.0.102) Callstate Change RINGING -> EARLY d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/1111@192.168.0.102 [BREAK] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_ivr_originate.c:3580 Originate Resulted in Success: [sofia/external/9892504286] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_session.c:908 Send signal sofia/external/9892504286 [BREAK] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/1111@192.168.0.102 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_ivr_bridge.c:1465 (sofia/external/9892504286) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_state_machine.c:472 (sofia/external/9892504286) Running State Change CS_EXCHANGE_MEDIA 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] switch_core_state_machine.c:538 (sofia/external/9892504286) State EXCHANGE_MEDIA 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.747070 [DEBUG] mod_sofia.c:594 SOFIA EXCHANGE_MEDIA d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/1111@192.168.0.102 [BREAK] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.747070 [DEBUG] sofia.c:6614 Channel sofia/internal/1111@192.168.0.102 entering state [early][183] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:14.947265 [DEBUG] switch_rtp.c:5856 Correct ip/port confirmed. d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:14.947265 [NOTICE] switch_core_io.c:1261 Activating write resampler d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:15.086914 [DEBUG] switch_rtp.c:5856 Correct ip/port confirmed. 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:15.086914 [NOTICE] switch_core_io.c:1261 Activating write resampler 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.927734 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.927734 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.947265 [DEBUG] sofia.c:6614 Channel sofia/external/9892504286 entering state [completing][200] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.947265 [DEBUG] sofia.c:6624 Remote SDP: 6197bbfc-c70f-4983-adad-08be37fe9a15 v=0 6197bbfc-c70f-4983-adad-08be37fe9a15 o=Dinstar 17020591 17020592 IN IP4 192.168.0.212 6197bbfc-c70f-4983-adad-08be37fe9a15 s=- 6197bbfc-c70f-4983-adad-08be37fe9a15 c=IN IP4 192.168.0.212 6197bbfc-c70f-4983-adad-08be37fe9a15 t=0 0 6197bbfc-c70f-4983-adad-08be37fe9a15 m=audio 8008 RTP/AVP 0 6197bbfc-c70f-4983-adad-08be37fe9a15 a=rtpmap:0 PCMU/8000 6197bbfc-c70f-4983-adad-08be37fe9a15 a=ptime:20 6197bbfc-c70f-4983-adad-08be37fe9a15 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.947265 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.947265 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/9892504286 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.966796 [DEBUG] sofia.c:6614 Channel sofia/external/9892504286 entering state [ready][200] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] switch_channel.c:3635 Send signal sofia/internal/1111@192.168.0.102 [BREAK] 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.966796 [NOTICE] sofia.c:7416 Channel [sofia/external/9892504286] has been answered 6197bbfc-c70f-4983-adad-08be37fe9a15 2016-08-24 10:39:36.966796 [DEBUG] switch_channel.c:3689 (sofia/external/9892504286) Callstate Change EARLY -> ACTIVE d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] mod_sofia.c:780 Local SDP sofia/internal/1111@192.168.0.102: d0da2ec7-b841-4ae5-a5ca-3c2020d8752c v=0 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c o=FreeSWITCH 1471983578 1471983580 IN IP4 192.168.0.102 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c s=FreeSWITCH d0da2ec7-b841-4ae5-a5ca-3c2020d8752c c=IN IP4 192.168.0.102 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c t=0 0 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c m=audio 31776 RTP/AVP 9 101 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=rtpmap:9 G722/8000 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=rtpmap:101 telephone-event/8000 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=fmtp:101 0-16 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=ptime:20 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c a=sendrecv d0da2ec7-b841-4ae5-a5ca-3c2020d8752c d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/1111@192.168.0.102 [BREAK] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [NOTICE] switch_ivr_bridge.c:496 Channel [sofia/internal/1111@192.168.0.102] has been answered d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/1111@192.168.0.102 [BREAK] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] switch_channel.c:3494 sofia/internal/1111@192.168.0.102 process uuid_record d0da2ec7-b841-4ae5-a5ca-3c2020d8752c start C:/Program Files/FreeSWITCH/recordings/192.168.0.102/archive/2016/Aug/24/d0da2ec7-b841-4ae5-a5ca-3c2020d8752c.wav: uuid_record(d0da2ec7-b841-4ae5-a5ca-3c2020d8752c start C:/Program Files/FreeSWITCH/recordings/192.168.0.102/archive/2016/Aug/24/d0da2ec7-b841-4ae5-a5ca-3c2020d8752c.wav) d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [INFO] mod_native_file.c:101 Opening File [C:/Program-in.G722] 16000hz 2016-08-24 10:39:36.966796 [INFO] mod_native_file.c:101 Opening File [C:/Program-out.G722] 16000hz d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] sofia.c:6614 Channel sofia/internal/1111@192.168.0.102 entering state [completed][200] d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] switch_core_media_bug.c:534 Attaching BUG to sofia/internal/1111@192.168.0.102 d0da2ec7-b841-4ae5-a5ca-3c2020d8752c 2016-08-24 10:39:36.966796 [DEBUG] switch_channel.c:3689 (sofia/internal/1111@192.168.0.102) Callstate Change EARLY -> ACTIVE © Copyright 2008 - 2016 fusionpbx.com. All rights reserved. 32