Hi all,
I am having an issue with outbound route. I have a gateway to route all 11 digit numbers to connect outside, when extensions dial any 11 digit number the line gets drop immediately from the caller party but still ring the dialed number and once the other party receive the call dose not hear any sound and after 30 second line cut off automatically.
if anyone can help me out that will be really grateful.
i have attached the log with global siptrace on as blow:
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:28.759899:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 308 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:28.760292:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
User-Agent: FreeSWITCH
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:16.678646 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1003@64.187.xxx.xxx:5060 [e13aca74-24b9-477f-a7ac-198856673ad6]
2018-04-27 13:31:16.678646 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_NEW (Cur 1 Tot 59)
2018-04-27 13:31:16.678646 [DEBUG] sofia.c:9873 sofia/internal/1003@64.187.xxx.xxx:5060 receiving invite from 64.187.xxx.xxx:5060 version: 1.6.20 -37-987c9b9 64bit
2018-04-27 13:31:16.678646 [DEBUG] sofia.c:9989 IP 64.187.xxx.xxx Approved by acl "domains[1003@103.110.xxx.xxx]". Access Granted.
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:10127 Authenticating user 1003@103.110.xxx.xxx
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [received][100]
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1003@64.187.xxx.xxx:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
2018-04-27 13:31:16.699132 [DEBUG] switch_core_codec.c:111 sofia/internal/1003@64.187.xxx.xxx:5060 Original read codec set to G729:18
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4767 sofia/internal/1003@64.187.xxx.xxx:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:7507 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_NEW -> CS_INIT
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1003@64.187.xxx.xxx:5060) State NEW
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_INIT (Cur 1 Tot 59)
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@64.187.xxx.xxx:5060) State INIT
2018-04-27 13:31:16.699132 [DEBUG] mod_sofia.c:90 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA INIT
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003@64.187.xxx.xxx:5060 Standard INIT
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_INIT -> CS_ROUTING
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@64.187.xxx.xxx:5060) State INIT going to sleep
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_ROUTING (Cur 1 Tot 59)
2018-04-27 13:31:16.699132 [DEBUG] switch_channel.c:2249 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change DOWN -> RINGING
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@64.187.xxx.xxx:5060) State ROUTING
2018-04-27 13:31:16.699132 [DEBUG] mod_sofia.c:143 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA ROUTING
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1003@64.187.xxx.xxx:5060 Standard ROUTING
2018-04-27 13:31:16.699132 [INFO] mod_dialplan_xml.c:637 Processing 271219 <1003>->09638340409 in context 103.110.xxx.xxx
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->user_exists] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2018-04-27 13:31:16.758639 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.779058 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(user_exists=false)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [user_exists]=[false]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->caller-details] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(caller_destination=${destination_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(caller_destination=09638340409)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [caller_destination]=[09638340409]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(caller_id_name=1003)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [caller_id_name]=[1003]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(caller_id_number=${caller_id_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(caller_id_number=1003)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [caller_id_number]=[1003]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->call-direction] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 ANTI-Action export(call_direction=local)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->variables] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->user_record] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
2018-04-27 13:31:16.779058 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(user_record=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [user_record]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(from_user_exists=false)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [from_user_exists]=[false]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->redial] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [redial] destination_number(09638340409) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->speed_dial] continue=false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [speed_dial] destination_number(09638340409) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->default_caller_id] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(emergency_caller_id_name=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(emergency_caller_id_number=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(outbound_caller_id_name=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(outbound_caller_id_number=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->GETCO-01.11d] continue=false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [GETCO-01.11d] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [GETCO-01.11d] destination_number(09638340409) =~ /^\+?(\d{11})$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action export(call_direction=outbound)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action unset(call_timeout)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(inherit_codec=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(ignore_display_updates=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(callee_id_number=09638340409)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(continue_on_fail=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action bridge(sofia/gateway/69bf0a1d-8bf1-4983-984a-0e88a9ed3e17/09638340409)
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_ROUTING -> CS_EXECUTE
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@64.187.xxx.xxx:5060) State ROUTING going to sleep
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_EXECUTE (Cur 1 Tot 59)
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@64.187.xxx.xxx:5060) State EXECUTE
2018-04-27 13:31:16.799455 [DEBUG] mod_sofia.c:198 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA EXECUTE
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1003@64.187.xxx.xxx:5060 Standard EXECUTE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 export(call_direction=local)
2018-04-27 13:31:16.799455 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[local]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 export(origination_callee_id_name=09638340409)
2018-04-27 13:31:16.799455 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[09638340409]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(RFC2822_DATE=Fri, 27 Apr 2018 13:31:16 -0400)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [RFC2822_DATE]=[Fri, 27 Apr 2018 13:31:16 -0400]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 hash(insert/103.110.xxx.xxx-last_dial/1003/09638340409)
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(sip_h_X-accountcode=)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [sip_h_X-accountcode]=[UNDEF]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 export(call_direction=outbound)
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[outbound]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 unset(call_timeout)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1693 UNSET [call_timeout]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(hangup_after_bridge=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [hangup_after_bridge]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(effective_caller_id_name=)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [effective_caller_id_name]=[UNDEF]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(effective_caller_id_number=)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [effective_caller_id_number]=[UNDEF]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(inherit_codec=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [inherit_codec]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(ignore_display_updates=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [ignore_display_updates]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(callee_id_number=09638340409)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [callee_id_number]=[09638340409]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(continue_on_fail=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [continue_on_fail]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 bridge(sofia/gateway/69bf0a1d-8bf1-4983-984a-0e88a9ed3e17/09638340409)
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [domain_name]=[103.110.xxx.xxx] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [origination_callee_id_name]=[09638340409] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2018-04-27 13:31:16.818893 [NOTICE] switch_channel.c:1104 New Channel sofia/external/09638340409 [75bd3b01-0772-49fa-9a68-fd5932ee1f42]
2018-04-27 13:31:16.818893 [DEBUG] mod_sofia.c:4819 (sofia/external/09638340409) State Change CS_NEW -> CS_INIT
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_INIT (Cur 2 Tot 60)
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:627 (sofia/external/09638340409) State INIT
2018-04-27 13:31:16.818893 [DEBUG] mod_sofia.c:90 sofia/external/09638340409 SOFIA INIT
2018-04-27 13:31:16.818893 [DEBUG] sofia_glue.c:1295 sofia/external/09638340409 sending invite version: 1.6.20 -37-987c9b9 64bit
Local SDP:
v=0
o=FreeSWITCH 1524817630 1524817631 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32646 RTP/AVP 18 0 8 101 13
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
send 1160 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:28.888928:
------------------------------------------------------------------------
INVITE sip:09638340409@182.16.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK1aS6cmKc31DyF
Max-Forwards: 69
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 INVITE
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 319
X-FS-Support: update_display,send_info
Remote-Party-ID: "271219" <sip:1003@182.16.xxx.xxx>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1524817630 1524817631 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32646 RTP/AVP 18 0 8 101 13
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:40 sofia/external/09638340409 Standard INIT
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:48 (sofia/external/09638340409) State Change CS_INIT -> CS_ROUTING
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:627 (sofia/external/09638340409) State INIT going to sleep
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_ROUTING (Cur 2 Tot 60)
2018-04-27 13:31:16.818893 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [calling][0]
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:643 (sofia/external/09638340409) State ROUTING
2018-04-27 13:31:16.818893 [DEBUG] mod_sofia.c:143 sofia/external/09638340409 SOFIA ROUTING
2018-04-27 13:31:16.818893 [DEBUG] switch_ivr_originate.c:67 (sofia/external/09638340409) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:643 (sofia/external/09638340409) State ROUTING going to sleep
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 60)
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:662 (sofia/external/09638340409) State CONSUME_MEDIA
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:662 (sofia/external/09638340409) State CONSUME_MEDIA going to sleep
recv 310 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:28.918365:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK1aS6cmKc31DyF;received=43.250.83.42
To: <sip:09638340409@182.16.xxx.xxx>
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 INVITE
Content-Length: 0
------------------------------------------------------------------------
recv 460 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:28.948835:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK1aS6cmKc31DyF
Record-Route: <sip:182.16.xxx.xxx;lr>
To: <sip:09638340409@182.16.xxx.xxx>
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 INVITE
Server: Sippy
WWW-Authenticate: Digest realm="182.16.xxx.xxx",nonce="919a4f7f5aa57a1227e9911223651be3974c"
Content-Length: 0
------------------------------------------------------------------------
send 321 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:28.948918:
------------------------------------------------------------------------
ACK sip:09638340409@182.16.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK1aS6cmKc31DyF
Max-Forwards: 69
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 ACK
Content-Length: 0
------------------------------------------------------------------------
send 1369 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:28.949159:
------------------------------------------------------------------------
INVITE sip:09638340409@182.16.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK2KjZeF4F0a4gB
Max-Forwards: 69
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="919a4f7f5aa57a1227e9911223651be3974c", algorithm=MD5, uri="sip:09638340409@182.16.xxx.xxx", response="7437893f5fea864b3b43d1527b7521bf"
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 319
X-FS-Support: update_display,send_info
Remote-Party-ID: "271219" <sip:1003@182.16.xxx.xxx>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1524817630 1524817631 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32646 RTP/AVP 18 0 8 101 13
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:16.878939 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [calling][0]
recv 310 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:28.953727:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK2KjZeF4F0a4gB;received=43.250.83.42
To: <sip:09638340409@182.16.xxx.xxx>
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Content-Length: 0
------------------------------------------------------------------------
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:29.263205:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 308 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:29.263376:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
User-Agent: FreeSWITCH
Content-Length: 0
------------------------------------------------------------------------
recv 386 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:29.514347:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK2KjZeF4F0a4gB
Record-Route: <sip:182.16.xxx.xxx;lr>
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Server: Sippy
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:17.438740 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [proceeding][180]
2018-04-27 13:31:17.438740 [NOTICE] sofia.c:7192 Ring-Ready sofia/external/09638340409!
2018-04-27 13:31:17.438740 [DEBUG] switch_channel.c:3346 (sofia/external/09638340409) Callstate Change DOWN -> RINGING
2018-04-27 13:31:17.438740 [INFO] switch_ivr_originate.c:1215 Sending early media
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/1003@64.187.xxx.xxx:5060] 192.168.0.109 port 32680 -> 64.187.xxx.xxx port 26750 codec: 18 ms: 20
2018-04-27 13:31:17.438740 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:7180 sofia/internal/1003@64.187.xxx.xxx:5060 Set 2833 dtmf send payload to 101
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:7187 sofia/internal/1003@64.187.xxx.xxx:5060 Set 2833 dtmf receive payload to 101
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:7210 sofia/internal/1003@64.187.xxx.xxx:5060 Set rtp dtmf delay to 40
2018-04-27 13:31:17.438740 [DEBUG] mod_sofia.c:2364 Ring SDP:
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2018-04-27 13:31:17.438740 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/internal/1003@64.187.xxx.xxx:5060!
2018-04-27 13:31:17.458810 [DEBUG] switch_channel.c:3474 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change RINGING -> EARLY
send 1149 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:29.525968:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:17.458810 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [early][183]
2018-04-27 13:31:17.458810 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2018-04-27 13:31:17.458810 [DEBUG] switch_core_codec.c:223 sofia/internal/1003@64.187.xxx.xxx:5060 Push codec L16:100
2018-04-27 13:31:17.458810 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(2000,4000,440,480)]
2018-04-27 13:31:17.818678 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:30.264027:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 1149 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:30.264196:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:32.264605:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 1149 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:32.264768:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 667 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:38.768719:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK2KjZeF4F0a4gB
Record-Route: <sip:182.16.xxx.xxx;lr>
Contact: "Anonymous"<sip:182.16.xxx.xxx:5061>
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Content-Type: application/sdp
Server: Sippy
H323-credit-time: 3600
Content-Length: 184
v=0
o=Sippy 187488448 1 IN IP4 182.16.xxx.xxx
s=-
t=0 0
m=audio 42702 RTP/AVP 8 101
c=IN IP4 182.16.xxx.xxx
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
------------------------------------------------------------------------
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [completing][200]
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=Sippy 187488448 1 IN IP4 182.16.xxx.xxx
s=-
t=0 0
m=audio 42702 RTP/AVP 8 101
c=IN IP4 182.16.xxx.xxx
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
send 706 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:38.771005:
------------------------------------------------------------------------
ACK sip:182.16.xxx.xxx:5061 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK3vBrgaNKXKt3p
Route: <sip:182.16.xxx.xxx;lr>
Max-Forwards: 70
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 ACK
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="919a4f7f5aa57a1227e9911223651be3974c", algorithm=MD5, uri="sip:09638340409@182.16.xxx.xxx", response="7437893f5fea864b3b43d1527b7521bf"
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [ready][200]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/09638340409 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2018-04-27 13:31:26.698676 [DEBUG] switch_core_codec.c:111 sofia/external/09638340409 Original read codec set to PCMA:8
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4767 sofia/external/09638340409 Set 2833 dtmf send payload to 101 recv payload to 101
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/09638340409] 192.168.0.109 port 32646 -> 182.16.xxx.xxx port 42702 codec: 8 ms: 20
2018-04-27 13:31:26.698676 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:7180 sofia/external/09638340409 Set 2833 dtmf send payload to 101
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:7187 sofia/external/09638340409 Set 2833 dtmf receive payload to 101
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:7210 sofia/external/09638340409 Set rtp dtmf delay to 40
2018-04-27 13:31:26.698676 [NOTICE] sofia.c:8218 Channel [sofia/external/09638340409] has been answered
2018-04-27 13:31:26.698676 [DEBUG] switch_channel.c:3773 (sofia/external/09638340409) Callstate Change RINGING -> ACTIVE
2018-04-27 13:31:26.698676 [DEBUG] switch_core_codec.c:248 sofia/internal/1003@64.187.xxx.xxx:5060 Restore previous codec G729:18.
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/1003@64.187.xxx.xxx:5060.
2018-04-27 13:31:26.698676 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/1003@64.187.xxx.xxx:5060:
v=0
o=FreeSWITCH 1524817597 1524817599 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:38.782641:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [completed][200]
2018-04-27 13:31:26.698676 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/1003@64.187.xxx.xxx:5060] has been answered
2018-04-27 13:31:26.718673 [DEBUG] switch_channel.c:3773 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change EARLY -> ACTIVE
2018-04-27 13:31:26.718673 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/external/09638340409]
2018-04-27 13:31:26.718673 [DEBUG] switch_ivr_bridge.c:1614 (sofia/external/09638340409) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2018-04-27 13:31:26.718673 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 60)
2018-04-27 13:31:26.718673 [DEBUG] switch_core_state_machine.c:653 (sofia/external/09638340409) State EXCHANGE_MEDIA
2018-04-27 13:31:26.718673 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:39.283848:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:27.638664 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:40.284808:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:42.284867:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:46.285085:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:50.286878:
-----------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:54.287140:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:58.288495:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:02.290275:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 833 bytes to udp/[182.16.xxx.xxx]:5060 at 13:32:03.570145:
------------------------------------------------------------------------
REGISTER sip:182.16.xxx.xxx;transport=udp SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK454gj55ptvgpj
Max-Forwards: 70
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
To: <sip:9613840042@182.16.xxx.xxx>
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081752 REGISTER
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
Expires: 800
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="1524849627:26887f7d9fd50d916dbf3ea08f39c7ff", algorithm=MD5, uri="sip:182.16.xxx.xxx;transport=udp", response="b7e2edb9150211d985f315bb3ee0ff96"
Content-Length: 0
------------------------------------------------------------------------
recv 435 bytes from udp/[182.16.xxx.xxx]:5060 at 13:32:03.576619:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK454gj55ptvgpj;received=43.250.83.42
To: <sip:9613840042@182.16.xxx.xxx>;tag=fc6add60
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081752 REGISTER
WWW-Authenticate: Digest nonce="1524850323:fde1ac8b9791e6653bb1512f0c53f3e4",algorithm=MD5,realm="182.16.xxx.xxx"
Content-Length: 0
------------------------------------------------------------------------
send 833 bytes to udp/[182.16.xxx.xxx]:5060 at 13:32:03.576885:
------------------------------------------------------------------------
REGISTER sip:182.16.xxx.xxx;transport=udp SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK5ey9K0ptQ568D
Max-Forwards: 70
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
To: <sip:9613840042@182.16.xxx.xxx>
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081753 REGISTER
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
Expires: 800
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="1524850323:fde1ac8b9791e6653bb1512f0c53f3e4", algorithm=MD5, uri="sip:182.16.xxx.xxx;transport=udp", response="fb76b2ccc31a3960ae73a1f7d261812c"
Content-Length: 0
------------------------------------------------------------------------
recv 542 bytes from udp/[182.16.xxx.xxx]:5060 at 13:32:03.603454:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK5ey9K0ptQ568D;received=43.250.83.42
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>;expires=800
To: <sip:9613840042@182.16.xxx.xxx>;tag=3714421f
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081753 REGISTER
Date: Fri, 27 Apr 2018 17:32:03 GMT
PortaBilling: available-funds:198.50 currency:BDT
Content-Length: 0
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:06.292266:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:10.294049:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:10.783654:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:58.718927 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [terminating][0]
2018-04-27 13:31:58.718927 [NOTICE] sofia.c:8273 Hangup sofia/internal/1003@64.187.xxx.xxx:5060 [CS_EXECUTE] [NORMAL_UNSPECIFIED]
2018-04-27 13:31:58.718927 [DEBUG] switch_ivr_bridge.c:712 sofia/internal/1003@64.187.xxx.xxx:5060 ending bridge by request from read function
2018-04-27 13:31:58.718927 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/1003@64.187.xxx.xxx:5060]
2018-04-27 13:31:58.738695 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/1003@64.187.xxx.xxx:5060 ending bridge by request from write function
2018-04-27 13:31:58.738695 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/external/09638340409]
2018-04-27 13:31:58.738695 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/external/09638340409 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:653 (sofia/external/09638340409) State EXCHANGE_MEDIA going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_HANGUP (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:850 (sofia/external/09638340409) Callstate Change ACTIVE -> HANGUP
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/external/09638340409) State HANGUP
2018-04-27 13:31:58.738695 [DEBUG] mod_sofia.c:438 Channel sofia/external/09638340409 hanging up, cause: NORMAL_CLEARING
2018-04-27 13:31:58.738695 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/09638340409
send 764 bytes to udp/[182.16.xxx.xxx]:5060 at 13:32:10.815753:
------------------------------------------------------------------------
BYE sip:182.16.xxx.xxx:5061 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK6QQ2NU7XmeXUS
Route: <sip:182.16.xxx.xxx;lr>
Max-Forwards: 70
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089082 BYE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="919a4f7f5aa57a1227e9911223651be3974c", algorithm=MD5, uri="sip:182.16.xxx.xxx:5061", response="4940f3bb545d094e7f53e96323b63ebc"
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:60 sofia/external/09638340409 Standard HANGUP, cause: NORMAL_CLEARING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/external/09638340409) State HANGUP going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:619 (sofia/external/09638340409) State Change CS_HANGUP -> CS_REPORTING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_REPORTING (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:938 (sofia/external/09638340409) State REPORTING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:174 sofia/external/09638340409 Standard REPORTING, cause: NORMAL_CLEARING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:938 (sofia/external/09638340409) State REPORTING going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:610 (sofia/external/09638340409) State Change CS_REPORTING -> CS_DESTROY
2018-04-27 13:31:58.738695 [DEBUG] switch_core_session.c:1665 Session 60 (sofia/external/09638340409) Locked, Waiting on external entities
2018-04-27 13:31:58.738695 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/1003@64.187.xxx.xxx:5060 skip receive message [UNBRIDGE] (channel is hungup already)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_session.c:2815 sofia/internal/1003@64.187.xxx.xxx:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@64.187.xxx.xxx:5060) State EXECUTE going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_HANGUP (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change ACTIVE -> HANGUP
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@64.187.xxx.xxx:5060) State HANGUP
2018-04-27 13:31:58.738695 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1003@64.187.xxx.xxx:5060 hanging up, cause: NORMAL_UNSPECIFIED
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1003@64.187.xxx.xxx:5060 Standard HANGUP, cause: NORMAL_UNSPECIFIED
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@64.187.xxx.xxx:5060) State HANGUP going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_HANGUP -> CS_REPORTING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_REPORTING (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@64.187.xxx.xxx:5060) State REPORTING
2018-04-27 13:31:58.759012 [NOTICE] switch_core_session.c:1683 Session 60 (sofia/external/09638340409) Ended
2018-04-27 13:31:58.759012 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/09638340409 [CS_DESTROY]
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:741 (sofia/external/09638340409) Running State Change CS_DESTROY (Cur 1 Tot 60)
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:751 (sofia/external/09638340409) State DESTROY
2018-04-27 13:31:58.759012 [DEBUG] mod_sofia.c:343 sofia/external/09638340409 SOFIA DESTROY
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:181 sofia/external/09638340409 Standard DESTROY
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:751 (sofia/external/09638340409) State DESTROY going to sleep
recv 341 bytes from udp/[182.16.xxx.xxx]:5060 at 13:32:10.838421:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK6QQ2NU7XmeXUS
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089082 BYE
Server: Sippy
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1003@64.187.xxx.xxx:5060 Standard REPORTING, cause: NORMAL_UNSPECIFIED
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@64.187.xxx.xxx:5060) State REPORTING going to sleep
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_REPORTING -> CS_DESTROY
2018-04-27 13:31:58.838645 [DEBUG] switch_core_session.c:1665 Session 59 (sofia/internal/1003@64.187.xxx.xxx:5060) Locked, Waiting on external entities
2018-04-27 13:31:58.838645 [NOTICE] switch_core_session.c:1683 Session 59 (sofia/internal/1003@64.187.xxx.xxx:5060) Ended
2018-04-27 13:31:58.838645 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1003@64.187.xxx.xxx:5060 [CS_DESTROY]
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_DESTROY (Cur 0 Tot 60)
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@64.187.xxx.xxx:5060) State DESTROY
2018-04-27 13:31:58.838645 [DEBUG] mod_sofia.c:343 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA DESTROY
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1003@64.187.xxx.xxx:5060 Standard DESTROY
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@64.187.xxx.xxx:5060) State DESTROY going to sleep
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:11.785187:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:13.786822:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:17.787180:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------
I am having an issue with outbound route. I have a gateway to route all 11 digit numbers to connect outside, when extensions dial any 11 digit number the line gets drop immediately from the caller party but still ring the dialed number and once the other party receive the call dose not hear any sound and after 30 second line cut off automatically.
if anyone can help me out that will be really grateful.
i have attached the log with global siptrace on as blow:
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:28.759899:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 308 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:28.760292:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
User-Agent: FreeSWITCH
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:16.678646 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1003@64.187.xxx.xxx:5060 [e13aca74-24b9-477f-a7ac-198856673ad6]
2018-04-27 13:31:16.678646 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_NEW (Cur 1 Tot 59)
2018-04-27 13:31:16.678646 [DEBUG] sofia.c:9873 sofia/internal/1003@64.187.xxx.xxx:5060 receiving invite from 64.187.xxx.xxx:5060 version: 1.6.20 -37-987c9b9 64bit
2018-04-27 13:31:16.678646 [DEBUG] sofia.c:9989 IP 64.187.xxx.xxx Approved by acl "domains[1003@103.110.xxx.xxx]". Access Granted.
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:10127 Authenticating user 1003@103.110.xxx.xxx
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [received][100]
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1003@64.187.xxx.xxx:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
2018-04-27 13:31:16.699132 [DEBUG] switch_core_codec.c:111 sofia/internal/1003@64.187.xxx.xxx:5060 Original read codec set to G729:18
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-04-27 13:31:16.699132 [DEBUG] switch_core_media.c:4767 sofia/internal/1003@64.187.xxx.xxx:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2018-04-27 13:31:16.699132 [DEBUG] sofia.c:7507 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_NEW -> CS_INIT
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1003@64.187.xxx.xxx:5060) State NEW
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_INIT (Cur 1 Tot 59)
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@64.187.xxx.xxx:5060) State INIT
2018-04-27 13:31:16.699132 [DEBUG] mod_sofia.c:90 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA INIT
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003@64.187.xxx.xxx:5060 Standard INIT
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_INIT -> CS_ROUTING
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@64.187.xxx.xxx:5060) State INIT going to sleep
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_ROUTING (Cur 1 Tot 59)
2018-04-27 13:31:16.699132 [DEBUG] switch_channel.c:2249 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change DOWN -> RINGING
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@64.187.xxx.xxx:5060) State ROUTING
2018-04-27 13:31:16.699132 [DEBUG] mod_sofia.c:143 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA ROUTING
2018-04-27 13:31:16.699132 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1003@64.187.xxx.xxx:5060 Standard ROUTING
2018-04-27 13:31:16.699132 [INFO] mod_dialplan_xml.c:637 Processing 271219 <1003>->09638340409 in context 103.110.xxx.xxx
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->user_exists] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2018-04-27 13:31:16.758639 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.779058 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(user_exists=false)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [user_exists]=[false]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->caller-details] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(caller_destination=${destination_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(caller_destination=09638340409)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [caller_destination]=[09638340409]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(caller_id_name=1003)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [caller_id_name]=[1003]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(caller_id_number=${caller_id_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(caller_id_number=1003)
2018-04-27 13:31:16.779058 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [caller_id_number]=[1003]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->call-direction] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 ANTI-Action export(call_direction=local)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->variables] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->user_record] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
2018-04-27 13:31:16.779058 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(user_record=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [user_record]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5a8c0c8160 Connected.
2018-04-27 13:31:16.799455 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5a8c0c8160 released.
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(from_user_exists=false)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [from_user_exists]=[false]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->redial] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [redial] destination_number(09638340409) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->speed_dial] continue=false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (FAIL) [speed_dial] destination_number(09638340409) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->default_caller_id] continue=true
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(emergency_caller_id_name=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(emergency_caller_id_number=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(outbound_caller_id_name=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(outbound_caller_id_number=)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 parsing [103.110.xxx.xxx->GETCO-01.11d] continue=false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [GETCO-01.11d] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Regex (PASS) [GETCO-01.11d] destination_number(09638340409) =~ /^\+?(\d{11})$/ break=on-false
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action export(call_direction=outbound)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action unset(call_timeout)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(inherit_codec=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(ignore_display_updates=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(callee_id_number=09638340409)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action set(continue_on_fail=true)
Dialplan: sofia/internal/1003@64.187.xxx.xxx:5060 Action bridge(sofia/gateway/69bf0a1d-8bf1-4983-984a-0e88a9ed3e17/09638340409)
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_ROUTING -> CS_EXECUTE
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@64.187.xxx.xxx:5060) State ROUTING going to sleep
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_EXECUTE (Cur 1 Tot 59)
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@64.187.xxx.xxx:5060) State EXECUTE
2018-04-27 13:31:16.799455 [DEBUG] mod_sofia.c:198 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA EXECUTE
2018-04-27 13:31:16.799455 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1003@64.187.xxx.xxx:5060 Standard EXECUTE
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 export(call_direction=local)
2018-04-27 13:31:16.799455 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[local]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 export(origination_callee_id_name=09638340409)
2018-04-27 13:31:16.799455 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[09638340409]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(RFC2822_DATE=Fri, 27 Apr 2018 13:31:16 -0400)
2018-04-27 13:31:16.799455 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [RFC2822_DATE]=[Fri, 27 Apr 2018 13:31:16 -0400]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 hash(insert/103.110.xxx.xxx-last_dial/1003/09638340409)
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(sip_h_X-accountcode=)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [sip_h_X-accountcode]=[UNDEF]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 export(call_direction=outbound)
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[outbound]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 unset(call_timeout)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1693 UNSET [call_timeout]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(hangup_after_bridge=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [hangup_after_bridge]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(effective_caller_id_name=)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [effective_caller_id_name]=[UNDEF]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(effective_caller_id_number=)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [effective_caller_id_number]=[UNDEF]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(inherit_codec=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [inherit_codec]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(ignore_display_updates=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [ignore_display_updates]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(callee_id_number=09638340409)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [callee_id_number]=[09638340409]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 set(continue_on_fail=true)
2018-04-27 13:31:16.818893 [DEBUG] mod_dptools.c:1548 SET sofia/internal/1003@64.187.xxx.xxx:5060 [continue_on_fail]=[true]
EXECUTE sofia/internal/1003@64.187.xxx.xxx:5060 bridge(sofia/gateway/69bf0a1d-8bf1-4983-984a-0e88a9ed3e17/09638340409)
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [domain_name]=[103.110.xxx.xxx] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [origination_callee_id_name]=[09638340409] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_channel.c:1250 sofia/internal/1003@64.187.xxx.xxx:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2018-04-27 13:31:16.818893 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2018-04-27 13:31:16.818893 [NOTICE] switch_channel.c:1104 New Channel sofia/external/09638340409 [75bd3b01-0772-49fa-9a68-fd5932ee1f42]
2018-04-27 13:31:16.818893 [DEBUG] mod_sofia.c:4819 (sofia/external/09638340409) State Change CS_NEW -> CS_INIT
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_INIT (Cur 2 Tot 60)
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:627 (sofia/external/09638340409) State INIT
2018-04-27 13:31:16.818893 [DEBUG] mod_sofia.c:90 sofia/external/09638340409 SOFIA INIT
2018-04-27 13:31:16.818893 [DEBUG] sofia_glue.c:1295 sofia/external/09638340409 sending invite version: 1.6.20 -37-987c9b9 64bit
Local SDP:
v=0
o=FreeSWITCH 1524817630 1524817631 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32646 RTP/AVP 18 0 8 101 13
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
send 1160 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:28.888928:
------------------------------------------------------------------------
INVITE sip:09638340409@182.16.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK1aS6cmKc31DyF
Max-Forwards: 69
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 INVITE
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 319
X-FS-Support: update_display,send_info
Remote-Party-ID: "271219" <sip:1003@182.16.xxx.xxx>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1524817630 1524817631 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32646 RTP/AVP 18 0 8 101 13
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:40 sofia/external/09638340409 Standard INIT
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:48 (sofia/external/09638340409) State Change CS_INIT -> CS_ROUTING
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:627 (sofia/external/09638340409) State INIT going to sleep
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_ROUTING (Cur 2 Tot 60)
2018-04-27 13:31:16.818893 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [calling][0]
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:643 (sofia/external/09638340409) State ROUTING
2018-04-27 13:31:16.818893 [DEBUG] mod_sofia.c:143 sofia/external/09638340409 SOFIA ROUTING
2018-04-27 13:31:16.818893 [DEBUG] switch_ivr_originate.c:67 (sofia/external/09638340409) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:643 (sofia/external/09638340409) State ROUTING going to sleep
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 60)
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:662 (sofia/external/09638340409) State CONSUME_MEDIA
2018-04-27 13:31:16.818893 [DEBUG] switch_core_state_machine.c:662 (sofia/external/09638340409) State CONSUME_MEDIA going to sleep
recv 310 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:28.918365:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK1aS6cmKc31DyF;received=43.250.83.42
To: <sip:09638340409@182.16.xxx.xxx>
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 INVITE
Content-Length: 0
------------------------------------------------------------------------
recv 460 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:28.948835:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK1aS6cmKc31DyF
Record-Route: <sip:182.16.xxx.xxx;lr>
To: <sip:09638340409@182.16.xxx.xxx>
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 INVITE
Server: Sippy
WWW-Authenticate: Digest realm="182.16.xxx.xxx",nonce="919a4f7f5aa57a1227e9911223651be3974c"
Content-Length: 0
------------------------------------------------------------------------
send 321 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:28.948918:
------------------------------------------------------------------------
ACK sip:09638340409@182.16.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK1aS6cmKc31DyF
Max-Forwards: 69
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089080 ACK
Content-Length: 0
------------------------------------------------------------------------
send 1369 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:28.949159:
------------------------------------------------------------------------
INVITE sip:09638340409@182.16.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK2KjZeF4F0a4gB
Max-Forwards: 69
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="919a4f7f5aa57a1227e9911223651be3974c", algorithm=MD5, uri="sip:09638340409@182.16.xxx.xxx", response="7437893f5fea864b3b43d1527b7521bf"
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 319
X-FS-Support: update_display,send_info
Remote-Party-ID: "271219" <sip:1003@182.16.xxx.xxx>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1524817630 1524817631 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32646 RTP/AVP 18 0 8 101 13
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:16.878939 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [calling][0]
recv 310 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:28.953727:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK2KjZeF4F0a4gB;received=43.250.83.42
To: <sip:09638340409@182.16.xxx.xxx>
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Content-Length: 0
------------------------------------------------------------------------
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:29.263205:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 308 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:29.263376:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
User-Agent: FreeSWITCH
Content-Length: 0
------------------------------------------------------------------------
recv 386 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:29.514347:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK2KjZeF4F0a4gB
Record-Route: <sip:182.16.xxx.xxx;lr>
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Server: Sippy
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:17.438740 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [proceeding][180]
2018-04-27 13:31:17.438740 [NOTICE] sofia.c:7192 Ring-Ready sofia/external/09638340409!
2018-04-27 13:31:17.438740 [DEBUG] switch_channel.c:3346 (sofia/external/09638340409) Callstate Change DOWN -> RINGING
2018-04-27 13:31:17.438740 [INFO] switch_ivr_originate.c:1215 Sending early media
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/1003@64.187.xxx.xxx:5060] 192.168.0.109 port 32680 -> 64.187.xxx.xxx port 26750 codec: 18 ms: 20
2018-04-27 13:31:17.438740 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:7180 sofia/internal/1003@64.187.xxx.xxx:5060 Set 2833 dtmf send payload to 101
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:7187 sofia/internal/1003@64.187.xxx.xxx:5060 Set 2833 dtmf receive payload to 101
2018-04-27 13:31:17.438740 [DEBUG] switch_core_media.c:7210 sofia/internal/1003@64.187.xxx.xxx:5060 Set rtp dtmf delay to 40
2018-04-27 13:31:17.438740 [DEBUG] mod_sofia.c:2364 Ring SDP:
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2018-04-27 13:31:17.438740 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/internal/1003@64.187.xxx.xxx:5060!
2018-04-27 13:31:17.458810 [DEBUG] switch_channel.c:3474 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change RINGING -> EARLY
send 1149 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:29.525968:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:17.458810 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [early][183]
2018-04-27 13:31:17.458810 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2018-04-27 13:31:17.458810 [DEBUG] switch_core_codec.c:223 sofia/internal/1003@64.187.xxx.xxx:5060 Push codec L16:100
2018-04-27 13:31:17.458810 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(2000,4000,440,480)]
2018-04-27 13:31:17.818678 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:30.264027:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 1149 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:30.264196:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 664 bytes from udp/[64.187.xxx.xxx]:5060 at 13:31:32.264605:
------------------------------------------------------------------------
INVITE sip:09638340409@103.110.xxx.xxx SIP/2.0
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>
Contact: 271219 <sip:429009@64.187.xxx.xxx:5060>
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 252
v=0
o=iTelSwitchPlus_7.0.1 22469580 22469580 IN IP4 64.187.xxx.xxx
s=Sip Session
c=IN IP4 64.187.xxx.xxx
t=0 0
m=audio 26750 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 1149 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:32.264768:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 667 bytes from udp/[182.16.xxx.xxx]:5060 at 13:31:38.768719:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK2KjZeF4F0a4gB
Record-Route: <sip:182.16.xxx.xxx;lr>
Contact: "Anonymous"<sip:182.16.xxx.xxx:5061>
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 INVITE
Content-Type: application/sdp
Server: Sippy
H323-credit-time: 3600
Content-Length: 184
v=0
o=Sippy 187488448 1 IN IP4 182.16.xxx.xxx
s=-
t=0 0
m=audio 42702 RTP/AVP 8 101
c=IN IP4 182.16.xxx.xxx
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
------------------------------------------------------------------------
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [completing][200]
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=Sippy 187488448 1 IN IP4 182.16.xxx.xxx
s=-
t=0 0
m=audio 42702 RTP/AVP 8 101
c=IN IP4 182.16.xxx.xxx
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
send 706 bytes to udp/[182.16.xxx.xxx]:5060 at 13:31:38.771005:
------------------------------------------------------------------------
ACK sip:182.16.xxx.xxx:5061 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK3vBrgaNKXKt3p
Route: <sip:182.16.xxx.xxx;lr>
Max-Forwards: 70
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089081 ACK
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="919a4f7f5aa57a1227e9911223651be3974c", algorithm=MD5, uri="sip:09638340409@182.16.xxx.xxx", response="7437893f5fea864b3b43d1527b7521bf"
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7084 Channel sofia/external/09638340409 entering state [ready][200]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/09638340409 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2018-04-27 13:31:26.698676 [DEBUG] switch_core_codec.c:111 sofia/external/09638340409 Original read codec set to PCMA:8
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:4767 sofia/external/09638340409 Set 2833 dtmf send payload to 101 recv payload to 101
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/09638340409] 192.168.0.109 port 32646 -> 182.16.xxx.xxx port 42702 codec: 8 ms: 20
2018-04-27 13:31:26.698676 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:7180 sofia/external/09638340409 Set 2833 dtmf send payload to 101
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:7187 sofia/external/09638340409 Set 2833 dtmf receive payload to 101
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:7210 sofia/external/09638340409 Set rtp dtmf delay to 40
2018-04-27 13:31:26.698676 [NOTICE] sofia.c:8218 Channel [sofia/external/09638340409] has been answered
2018-04-27 13:31:26.698676 [DEBUG] switch_channel.c:3773 (sofia/external/09638340409) Callstate Change RINGING -> ACTIVE
2018-04-27 13:31:26.698676 [DEBUG] switch_core_codec.c:248 sofia/internal/1003@64.187.xxx.xxx:5060 Restore previous codec G729:18.
2018-04-27 13:31:26.698676 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/1003@64.187.xxx.xxx:5060.
2018-04-27 13:31:26.698676 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/1003@64.187.xxx.xxx:5060:
v=0
o=FreeSWITCH 1524817597 1524817599 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:38.782641:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:26.698676 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [completed][200]
2018-04-27 13:31:26.698676 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/1003@64.187.xxx.xxx:5060] has been answered
2018-04-27 13:31:26.718673 [DEBUG] switch_channel.c:3773 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change EARLY -> ACTIVE
2018-04-27 13:31:26.718673 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/external/09638340409]
2018-04-27 13:31:26.718673 [DEBUG] switch_ivr_bridge.c:1614 (sofia/external/09638340409) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2018-04-27 13:31:26.718673 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 60)
2018-04-27 13:31:26.718673 [DEBUG] switch_core_state_machine.c:653 (sofia/external/09638340409) State EXCHANGE_MEDIA
2018-04-27 13:31:26.718673 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:39.283848:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-04-27 13:31:27.638664 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:40.284808:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:42.284867:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:46.285085:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:50.286878:
-----------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:54.287140:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:31:58.288495:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:02.290275:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 833 bytes to udp/[182.16.xxx.xxx]:5060 at 13:32:03.570145:
------------------------------------------------------------------------
REGISTER sip:182.16.xxx.xxx;transport=udp SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK454gj55ptvgpj
Max-Forwards: 70
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
To: <sip:9613840042@182.16.xxx.xxx>
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081752 REGISTER
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
Expires: 800
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="1524849627:26887f7d9fd50d916dbf3ea08f39c7ff", algorithm=MD5, uri="sip:182.16.xxx.xxx;transport=udp", response="b7e2edb9150211d985f315bb3ee0ff96"
Content-Length: 0
------------------------------------------------------------------------
recv 435 bytes from udp/[182.16.xxx.xxx]:5060 at 13:32:03.576619:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK454gj55ptvgpj;received=43.250.83.42
To: <sip:9613840042@182.16.xxx.xxx>;tag=fc6add60
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081752 REGISTER
WWW-Authenticate: Digest nonce="1524850323:fde1ac8b9791e6653bb1512f0c53f3e4",algorithm=MD5,realm="182.16.xxx.xxx"
Content-Length: 0
------------------------------------------------------------------------
send 833 bytes to udp/[182.16.xxx.xxx]:5060 at 13:32:03.576885:
------------------------------------------------------------------------
REGISTER sip:182.16.xxx.xxx;transport=udp SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK5ey9K0ptQ568D
Max-Forwards: 70
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
To: <sip:9613840042@182.16.xxx.xxx>
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081753 REGISTER
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>
Expires: 800
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="1524850323:fde1ac8b9791e6653bb1512f0c53f3e4", algorithm=MD5, uri="sip:182.16.xxx.xxx;transport=udp", response="fb76b2ccc31a3960ae73a1f7d261812c"
Content-Length: 0
------------------------------------------------------------------------
recv 542 bytes from udp/[182.16.xxx.xxx]:5060 at 13:32:03.603454:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport=5080;branch=z9hG4bK5ey9K0ptQ568D;received=43.250.83.42
Contact: <sip:gw+69bf0a1d-8bf1-4983-984a-0e88a9ed3e17@103.110.xxx.xxx:5080;transport=udp;gw=69bf0a1d-8bf1-4983-984a-0e88a9ed3e17>;expires=800
To: <sip:9613840042@182.16.xxx.xxx>;tag=3714421f
From: <sip:9613840042@182.16.xxx.xxx>;tag=racNUvptp3Xye
Call-ID: f54489d2-cf79-43a1-b556-091b2a07889f
CSeq: 122081753 REGISTER
Date: Fri, 27 Apr 2018 17:32:03 GMT
PortaBilling: available-funds:198.50 currency:BDT
Content-Length: 0
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:06.292266:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1110 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:10.294049:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.187.xxx.xxx:5060;branch=z9hG4bK-J7619-5119-bficbhdf
From: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
To: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
Call-Id: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 100160467 INVITE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 247
Remote-Party-ID: "09638340409" <sip:09638340409@103.110.xxx.xxx>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1524817597 1524817598 IN IP4 103.110.xxx.xxx
s=FreeSWITCH
c=IN IP4 103.110.xxx.xxx
t=0 0
m=audio 32680 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:10.783654:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:58.718927 [DEBUG] sofia.c:7084 Channel sofia/internal/1003@64.187.xxx.xxx:5060 entering state [terminating][0]
2018-04-27 13:31:58.718927 [NOTICE] sofia.c:8273 Hangup sofia/internal/1003@64.187.xxx.xxx:5060 [CS_EXECUTE] [NORMAL_UNSPECIFIED]
2018-04-27 13:31:58.718927 [DEBUG] switch_ivr_bridge.c:712 sofia/internal/1003@64.187.xxx.xxx:5060 ending bridge by request from read function
2018-04-27 13:31:58.718927 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/1003@64.187.xxx.xxx:5060]
2018-04-27 13:31:58.738695 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/1003@64.187.xxx.xxx:5060 ending bridge by request from write function
2018-04-27 13:31:58.738695 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/external/09638340409]
2018-04-27 13:31:58.738695 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/external/09638340409 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:653 (sofia/external/09638340409) State EXCHANGE_MEDIA going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_HANGUP (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:850 (sofia/external/09638340409) Callstate Change ACTIVE -> HANGUP
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/external/09638340409) State HANGUP
2018-04-27 13:31:58.738695 [DEBUG] mod_sofia.c:438 Channel sofia/external/09638340409 hanging up, cause: NORMAL_CLEARING
2018-04-27 13:31:58.738695 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/09638340409
send 764 bytes to udp/[182.16.xxx.xxx]:5060 at 13:32:10.815753:
------------------------------------------------------------------------
BYE sip:182.16.xxx.xxx:5061 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;rport;branch=z9hG4bK6QQ2NU7XmeXUS
Route: <sip:182.16.xxx.xxx;lr>
Max-Forwards: 70
From: "271219" <sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089082 BYE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Authorization: Digest username="9613840042", realm="182.16.xxx.xxx", nonce="919a4f7f5aa57a1227e9911223651be3974c", algorithm=MD5, uri="sip:182.16.xxx.xxx:5061", response="4940f3bb545d094e7f53e96323b63ebc"
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:60 sofia/external/09638340409 Standard HANGUP, cause: NORMAL_CLEARING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/external/09638340409) State HANGUP going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:619 (sofia/external/09638340409) State Change CS_HANGUP -> CS_REPORTING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/external/09638340409) Running State Change CS_REPORTING (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:938 (sofia/external/09638340409) State REPORTING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:174 sofia/external/09638340409 Standard REPORTING, cause: NORMAL_CLEARING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:938 (sofia/external/09638340409) State REPORTING going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:610 (sofia/external/09638340409) State Change CS_REPORTING -> CS_DESTROY
2018-04-27 13:31:58.738695 [DEBUG] switch_core_session.c:1665 Session 60 (sofia/external/09638340409) Locked, Waiting on external entities
2018-04-27 13:31:58.738695 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/1003@64.187.xxx.xxx:5060 skip receive message [UNBRIDGE] (channel is hungup already)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_session.c:2815 sofia/internal/1003@64.187.xxx.xxx:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@64.187.xxx.xxx:5060) State EXECUTE going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_HANGUP (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1003@64.187.xxx.xxx:5060) Callstate Change ACTIVE -> HANGUP
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@64.187.xxx.xxx:5060) State HANGUP
2018-04-27 13:31:58.738695 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1003@64.187.xxx.xxx:5060 hanging up, cause: NORMAL_UNSPECIFIED
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1003@64.187.xxx.xxx:5060 Standard HANGUP, cause: NORMAL_UNSPECIFIED
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@64.187.xxx.xxx:5060) State HANGUP going to sleep
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_HANGUP -> CS_REPORTING
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_REPORTING (Cur 2 Tot 60)
2018-04-27 13:31:58.738695 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@64.187.xxx.xxx:5060) State REPORTING
2018-04-27 13:31:58.759012 [NOTICE] switch_core_session.c:1683 Session 60 (sofia/external/09638340409) Ended
2018-04-27 13:31:58.759012 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/09638340409 [CS_DESTROY]
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:741 (sofia/external/09638340409) Running State Change CS_DESTROY (Cur 1 Tot 60)
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:751 (sofia/external/09638340409) State DESTROY
2018-04-27 13:31:58.759012 [DEBUG] mod_sofia.c:343 sofia/external/09638340409 SOFIA DESTROY
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:181 sofia/external/09638340409 Standard DESTROY
2018-04-27 13:31:58.759012 [DEBUG] switch_core_state_machine.c:751 (sofia/external/09638340409) State DESTROY going to sleep
recv 341 bytes from udp/[182.16.xxx.xxx]:5060 at 13:32:10.838421:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.110.xxx.xxx:5080;received=43.250.83.42;rport=5080;branch=z9hG4bK6QQ2NU7XmeXUS
To: <sip:09638340409@182.16.xxx.xxx>;tag=qlewd3yscp4i7qt2.i
From: "271219"<sip:9613840042@182.16.xxx.xxx>;tag=jNXS9UcpBB14B
Call-ID: a8ebf88e-c4e3-1236-2da2-080027a5255a
CSeq: 122089082 BYE
Server: Sippy
Content-Length: 0
------------------------------------------------------------------------
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1003@64.187.xxx.xxx:5060 Standard REPORTING, cause: NORMAL_UNSPECIFIED
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@64.187.xxx.xxx:5060) State REPORTING going to sleep
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1003@64.187.xxx.xxx:5060) State Change CS_REPORTING -> CS_DESTROY
2018-04-27 13:31:58.838645 [DEBUG] switch_core_session.c:1665 Session 59 (sofia/internal/1003@64.187.xxx.xxx:5060) Locked, Waiting on external entities
2018-04-27 13:31:58.838645 [NOTICE] switch_core_session.c:1683 Session 59 (sofia/internal/1003@64.187.xxx.xxx:5060) Ended
2018-04-27 13:31:58.838645 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1003@64.187.xxx.xxx:5060 [CS_DESTROY]
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1003@64.187.xxx.xxx:5060) Running State Change CS_DESTROY (Cur 0 Tot 60)
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@64.187.xxx.xxx:5060) State DESTROY
2018-04-27 13:31:58.838645 [DEBUG] mod_sofia.c:343 sofia/internal/1003@64.187.xxx.xxx:5060 SOFIA DESTROY
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1003@64.187.xxx.xxx:5060 Standard DESTROY
2018-04-27 13:31:58.838645 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@64.187.xxx.xxx:5060) State DESTROY going to sleep
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:11.785187:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:13.786822:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------
send 606 bytes to udp/[64.187.xxx.xxx]:5060 at 13:32:17.787180:
------------------------------------------------------------------------
BYE sip:429009@64.187.xxx.xxx:5060 SIP/2.0
Via: SIP/2.0/UDP 103.110.xxx.xxx;rport;branch=z9hG4bKBc8HZp7p24g3e
Max-Forwards: 70
From: <sip:09638340409@103.110.xxx.xxx>;tag=F9gZDtgvXytQr
To: 271219 <sip:1003@64.187.xxx.xxx:5060>;tag=374634873J28M
Call-ID: bfcebdhgdlscya8207640@64.187.xxx.xxx
CSeq: 122089101 BYE
Contact: <sip:09638340409@103.110.xxx.xxx:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Reason: SIP;cause=408;text="ACK Timeout"
Content-Length: 0
------------------------------------------------------------------------