[Freeswitch-users] Freeswitch trunk with router vodafone asterisk based

3 Apr 2019

      Good morning, 

I Have this error, 

Internet ——> Router Vodafone (Asteriskj Based Pstn Voip) 192.168.2.5  ——>  FS ( 192.168.2.10 ) ——-> Phone (192.168.2.41)

* Freeswitch is behind this router with static internal ip 192.168.2.10 and without internet. 

After config trunk with this router and add gateway in FS this trunk a have 
normal inbound/incoming calls and work ok. 
The error is on outbound call from this trunk, make the call and i don’t have returned audio to phone. 
After make from phone a call, call starting from router but with out ring back in phone and if answer the call from the other side 
rtp channel open and send packets to FS but not to Phone.

Can help me please? Thanks. Sorry my English is so bad.

Log from sip call outbound trace  

   ------------------------------------------------------------------------
   INVITE sip:401@192.168.2.10:5060;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.41;branch=z9hG4bK169fc7e5a6e1207bd
   Route: <sip:192.168.2.10:5060;lr;transport=udp>
   Max-Forwards: 70
   From: "7777" <sip:7777@192.168.2.10:5060>;tag=c607ca2663;epid=SC0c351a
   To: <sip:401@192.168.2.10:5060>
   Call-ID: 356f4bddc52d1c96
   CSeq: 2140941627 INVITE
   Allow: INVITE, ACK, CANCEL, BYE, REFER, NOTIFY, UPDATE
   Allow-Events: hold
   Contact: "7777" <sip:7777@192.168.2.41:5060;transport=udp>
   Min-SE: 90
   Session-Expires: 3600;refresher=uac
   Supported: replaces, 100rel, timer
   User-Agent: OpenStage_40_V3 R4.10.0      SIP  160728
   X-Siemens-Call-Type: ST-secure, ST-insecure
   Content-Type: application/sdp
   Content-Length: 352

   v=0
   o=OpenStage-Line_0 1646341531 67203184 IN IP4 192.168.2.41
   s=SIP Call
   c=IN IP4 192.168.2.41
   t=0 0
   m=audio 5010 RTP/AVP 8 0 18 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:18 G729/8000
   a=rtpmap:101 telephone-event/8000
   a=silenceSupp:off - - - -
   a=fmtp:18 annexb=no
   a=fmtp:101 0-15
   a=rtcp:5011 IN IP4 192.168.2.41
   a=sendrecv
   ------------------------------------------------------------------------
2019-04-01 23:19:16.604364 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/7777@192.168.2.10:5060 [1f84cc47-487d-495d-8d1f-a7dd2636fc43]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7777@192.168.2.10:5060) Running State Change CS_NEW (Cur 1 Tot 7)
2019-04-01 23:19:16.604364 [DEBUG] sofia.c:10092 sofia/internal/7777@192.168.2.10:5060 receiving invite from 192.168.2.41:5060 version: 1.8.5 -6-31281a0bf1 64bit
2019-04-01 23:19:16.604364 [DEBUG] sofia.c:7291 Channel sofia/internal/7777@192.168.2.10:5060 entering state [received][100]
2019-04-01 23:19:16.604364 [DEBUG] sofia.c:7301 Remote SDP:
v=0
o=OpenStage-Line_0 1646341531 67203184 IN IP4 192.168.2.41
s=SIP Call
c=IN IP4 192.168.2.41
t=0 0
m=audio 5010 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=rtcp:5011 IN IP4 192.168.2.41

2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:5394 Set telephone-event payload to 101@8000
2019-04-01 23:19:16.604364 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/7777@192.168.2.10:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2019-04-01 23:19:16.604364 [DEBUG] switch_core_codec.c:111 sofia/internal/7777@192.168.2.10:5060 Original read codec set to PCMA:8
2019-04-01 23:19:16.624363 [DEBUG] switch_core_media.c:5737 Set telephone-event payload to 101@8000
2019-04-01 23:19:16.624363 [DEBUG] switch_core_media.c:5795 sofia/internal/7777@192.168.2.10:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2019-04-01 23:19:16.624363 [DEBUG] sofia.c:7716 (sofia/internal/7777@192.168.2.10:5060) State Change CS_NEW -> CS_INIT
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/7777@192.168.2.10:5060) State NEW
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7777@192.168.2.10:5060) Running State Change CS_INIT (Cur 1 Tot 7)
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/7777@192.168.2.10:5060) State INIT
2019-04-01 23:19:16.624363 [DEBUG] mod_sofia.c:93 sofia/internal/7777@192.168.2.10:5060 SOFIA INIT
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:40 sofia/internal/7777@192.168.2.10:5060 Standard INIT
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/7777@192.168.2.10:5060) State Change CS_INIT -> CS_ROUTING
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/7777@192.168.2.10:5060) State INIT going to sleep
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7777@192.168.2.10:5060) Running State Change CS_ROUTING (Cur 1 Tot 7)
2019-04-01 23:19:16.624363 [DEBUG] switch_channel.c:2249 (sofia/internal/7777@192.168.2.10:5060) Callstate Change DOWN -> RINGING
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/7777@192.168.2.10:5060) State ROUTING
2019-04-01 23:19:16.624363 [DEBUG] mod_sofia.c:154 sofia/internal/7777@192.168.2.10:5060 SOFIA ROUTING
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:236 sofia/internal/7777@192.168.2.10:5060 Standard ROUTING
2019-04-01 23:19:16.624363 [INFO] mod_dialplan_xml.c:637 Processing 7777 <7777>->401 in context 192.168.2.10
send 296 bytes to udp/[192.168.2.41]:5060 at 23:19:16.624956:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.2.41;branch=z9hG4bK169fc7e5a6e1207bd;rport=5060
   From: "7777" <sip:7777@192.168.2.10:5060>;tag=c607ca2663;epid=SC0c351a
   To: <sip:401@192.168.2.10:5060>
   Call-ID: 356f4bddc52d1c96
   CSeq: 2140941627 INVITE
   User-Agent: FreeSWITCH
   Content-Length: 0

   ------------------------------------------------------------------------
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->user_exists] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(user_exists=false)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [user_exists]=[false]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->caller-details] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(caller_destination=${destination_number}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(caller_destination=401)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [caller_destination]=[401]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(caller_id_name=7777)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [caller_id_name]=[7777]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(caller_id_number=${caller_id_number}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(caller_id_number=7777)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [caller_id_number]=[7777]
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->variables] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->call-direction] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 ANTI-Action export(call_direction=local)
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->call_block] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [call_block] ${call_direction}() =~ /^inbound$/ break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->user_record] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(from_user_exists=true)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [from_user_exists]=[true]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(from_user_record=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [from_user_record]=[UNDEF]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->redial] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [redial] destination_number(401) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->speed_dial] continue=false
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (FAIL) [speed_dial] destination_number(401) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->default_caller_id] continue=true
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(emergency_caller_id_name=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(emergency_caller_id_number=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(outbound_caller_id_name=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(outbound_caller_id_number=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/7777@192.168.2.10:5060 parsing [192.168.2.10->fs.d3] continue=false
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [fs.d3] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 Regex (PASS) [fs.d3] destination_number(401) =~ /^(\d{3})$/ break=on-false
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action export(call_direction=outbound)
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action unset(call_timeout)
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(ignore_display_updates=true)
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(callee_id_number=401)
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action set(continue_on_fail=true)
Dialplan: sofia/internal/7777@192.168.2.10:5060 Action bridge({rtp_autoflush_during_bridge=false}sofia/gateway/af53c152-0f9c-4166-ad4f-3abf497e9abe/401)
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/7777@192.168.2.10:5060) State Change CS_ROUTING -> CS_EXECUTE
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/7777@192.168.2.10:5060) State ROUTING going to sleep
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7777@192.168.2.10:5060) Running State Change CS_EXECUTE (Cur 1 Tot 7)
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/7777@192.168.2.10:5060) State EXECUTE
2019-04-01 23:19:16.624363 [DEBUG] mod_sofia.c:209 sofia/internal/7777@192.168.2.10:5060 SOFIA EXECUTE
2019-04-01 23:19:16.624363 [DEBUG] switch_core_state_machine.c:328 sofia/internal/7777@192.168.2.10:5060 Standard EXECUTE
EXECUTE sofia/internal/7777@192.168.2.10:5060 export(origination_callee_id_name=401)
2019-04-01 23:19:16.624363 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[401]
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(RFC2822_DATE=Mon, 01 Apr 2019 23:19:16 +0300)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [RFC2822_DATE]=[Mon, 01 Apr 2019 23:19:16 +0300]
EXECUTE sofia/internal/7777@192.168.2.10:5060 export(call_direction=local)
2019-04-01 23:19:16.624363 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[local]
EXECUTE sofia/internal/7777@192.168.2.10:5060 hash(insert/192.168.2.10-last_dial/7777/401)
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(sip_h_X-accountcode=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [sip_h_X-accountcode]=[UNDEF]
EXECUTE sofia/internal/7777@192.168.2.10:5060 export(call_direction=outbound)
2019-04-01 23:19:16.624363 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[outbound]
EXECUTE sofia/internal/7777@192.168.2.10:5060 unset(call_timeout)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1743 UNSET [call_timeout]
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(hangup_after_bridge=true)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [hangup_after_bridge]=[true]
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(effective_caller_id_name=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [effective_caller_id_name]=[UNDEF]
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(effective_caller_id_number=)
2019-04-01 23:19:16.624363 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [effective_caller_id_number]=[UNDEF]
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(ignore_display_updates=true)
2019-04-01 23:19:16.644362 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [ignore_display_updates]=[true]
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(callee_id_number=401)
2019-04-01 23:19:16.644362 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [callee_id_number]=[401]
EXECUTE sofia/internal/7777@192.168.2.10:5060 set(continue_on_fail=true)
2019-04-01 23:19:16.644362 [DEBUG] mod_dptools.c:1598 SET sofia/internal/7777@192.168.2.10:5060 [continue_on_fail]=[true]
EXECUTE sofia/internal/7777@192.168.2.10:5060 bridge({rtp_autoflush_during_bridge=false}sofia/gateway/af53c152-0f9c-4166-ad4f-3abf497e9abe/401)
2019-04-01 23:19:16.644362 [DEBUG] switch_channel.c:1250 sofia/internal/7777@192.168.2.10:5060 EXPORTING[export_vars] [domain_name]=[192.168.2.10] to event
2019-04-01 23:19:16.644362 [DEBUG] switch_channel.c:1250 sofia/internal/7777@192.168.2.10:5060 EXPORTING[export_vars] [origination_callee_id_name]=[401] to event
2019-04-01 23:19:16.644362 [DEBUG] switch_channel.c:1250 sofia/internal/7777@192.168.2.10:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2019-04-01 23:19:16.644362 [DEBUG] switch_channel.c:1250 sofia/internal/7777@192.168.2.10:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2019-04-01 23:19:16.644362 [DEBUG] switch_ivr_originate.c:2204 Parsing global variables
2019-04-01 23:19:16.644362 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/401 [46e3d37c-4663-40fd-8a89-fbc58a624fb5]
2019-04-01 23:19:16.644362 [DEBUG] mod_sofia.c:5019 (sofia/internal/401) State Change CS_NEW -> CS_INIT
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/401) Running State Change CS_INIT (Cur 2 Tot 8)
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/401) State INIT
2019-04-01 23:19:16.644362 [DEBUG] mod_sofia.c:93 sofia/internal/401 SOFIA INIT
2019-04-01 23:19:16.644362 [DEBUG] sofia_glue.c:1302 sofia/internal/401 sending invite version: 1.8.5 -6-31281a0bf1 64bit
Local SDP:
v=0
o=FreeSWITCH 1554117544 1554117545 IN IP4 192.168.2.10
s=FreeSWITCH
c=IN IP4 192.168.2.10
t=0 0
m=audio 32412 RTP/AVP 8 101 13
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

2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:40 sofia/internal/401 Standard INIT
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/401) State Change CS_INIT -> CS_ROUTING
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/401) State INIT going to sleep
send 1235 bytes to udp/[192.168.2.5]:5060 at 23:19:16.646518:
   ------------------------------------------------------------------------
   INVITE sip:401@192.168.2.5 SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.10;rport;branch=z9hG4bKaD01jKXKySvej
   Max-Forwards: 69
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521186 INVITE
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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: 244
   X-Siemens-Call-Type: ST-secure, ST-insecure
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "7777" <sip:7777@192.168.2.5>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1554117544 1554117545 IN IP4 192.168.2.10
   s=FreeSWITCH
   c=IN IP4 192.168.2.10
   t=0 0
   m=audio 32412 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=rtpmap:13 CN/8000
   a=ptime:20
   ------------------------------------------------------------------------
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/401) Running State Change CS_ROUTING (Cur 2 Tot 8)
2019-04-01 23:19:16.644362 [DEBUG] sofia.c:7291 Channel sofia/internal/401 entering state [calling][0]
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/401) State ROUTING
2019-04-01 23:19:16.644362 [DEBUG] mod_sofia.c:154 sofia/internal/401 SOFIA ROUTING
2019-04-01 23:19:16.644362 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/401) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/401) State ROUTING going to sleep
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/401) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 8)
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/401) State CONSUME_MEDIA
2019-04-01 23:19:16.644362 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/401) State CONSUME_MEDIA going to sleep
recv 552 bytes from udp/[192.168.2.5]:5060 at 23:19:16.649851:
   ------------------------------------------------------------------------
   SIP/2.0 407 Proxy Authentication Required
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKaD01jKXKySvej;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as5f2ac2da
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521186 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4c256898"
   Content-Length: 0

   ------------------------------------------------------------------------
send 300 bytes to udp/[192.168.2.5]:5060 at 23:19:16.649935:
   ------------------------------------------------------------------------
   ACK sip:401@192.168.2.5 SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.10;rport;branch=z9hG4bKaD01jKXKySvej
   Max-Forwards: 69
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as5f2ac2da
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521186 ACK
   Content-Length: 0

   ------------------------------------------------------------------------
send 1403 bytes to udp/[192.168.2.5]:5060 at 23:19:16.650070:
   ------------------------------------------------------------------------
   INVITE sip:401@192.168.2.5 SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.10;rport;branch=z9hG4bKBpStmeeQU2j1D
   Max-Forwards: 69
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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
   Proxy-Authorization: Digest username="9999", realm="asterisk", nonce="4c256898", algorithm=MD5, uri="sip:401@192.168.2.5", response="63b6418f049af14ff2d44e17d7d72f34"
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 244
   X-Siemens-Call-Type: ST-secure, ST-insecure
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "7777" <sip:7777@192.168.2.5>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1554117544 1554117545 IN IP4 192.168.2.10
   s=FreeSWITCH
   c=IN IP4 192.168.2.10
   t=0 0
   m=audio 32412 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=rtpmap:13 CN/8000
   a=ptime:20
   ------------------------------------------------------------------------
2019-04-01 23:19:16.644362 [DEBUG] sofia.c:7291 Channel sofia/internal/401 entering state [calling][0]
recv 503 bytes from udp/[192.168.2.5]:5060 at 23:19:16.654639:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Content-Length: 0

   ------------------------------------------------------------------------
recv 535 bytes from udp/[192.168.2.5]:5060 at 23:19:16.678633:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Require: timer
   Content-Length: 0

   ------------------------------------------------------------------------
send 1403 bytes to udp/[192.168.2.5]:5060 at 23:19:17.650231:
   ------------------------------------------------------------------------
   INVITE sip:401@192.168.2.5 SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.10;rport;branch=z9hG4bKBpStmeeQU2j1D
   Max-Forwards: 69
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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
   Proxy-Authorization: Digest username="9999", realm="asterisk", nonce="4c256898", algorithm=MD5, uri="sip:401@192.168.2.5", response="63b6418f049af14ff2d44e17d7d72f34"
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 244
   X-Siemens-Call-Type: ST-secure, ST-insecure
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "7777" <sip:7777@192.168.2.5>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1554117544 1554117545 IN IP4 192.168.2.10
   s=FreeSWITCH
   c=IN IP4 192.168.2.10
   t=0 0
   m=audio 32412 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=rtpmap:13 CN/8000
   a=ptime:20
   ------------------------------------------------------------------------
recv 503 bytes from udp/[192.168.2.5]:5060 at 23:19:17.652893:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Content-Length: 0

   ------------------------------------------------------------------------
send 1403 bytes to udp/[192.168.2.5]:5060 at 23:19:19.650267:
   ------------------------------------------------------------------------
   INVITE sip:401@192.168.2.5 SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.10;rport;branch=z9hG4bKBpStmeeQU2j1D
   Max-Forwards: 69
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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
   Proxy-Authorization: Digest username="9999", realm="asterisk", nonce="4c256898", algorithm=MD5, uri="sip:401@192.168.2.5", response="63b6418f049af14ff2d44e17d7d72f34"
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 244
   X-Siemens-Call-Type: ST-secure, ST-insecure
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "7777" <sip:7777@192.168.2.5>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1554117544 1554117545 IN IP4 192.168.2.10
   s=FreeSWITCH
   c=IN IP4 192.168.2.10
   t=0 0
   m=audio 32412 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=rtpmap:13 CN/8000
   a=ptime:20
   ------------------------------------------------------------------------
recv 503 bytes from udp/[192.168.2.5]:5060 at 23:19:19.656053:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Content-Length: 0

   ------------------------------------------------------------------------
send 1403 bytes to udp/[192.168.2.5]:5060 at 23:19:23.650317:
   ------------------------------------------------------------------------
   INVITE sip:401@192.168.2.5 SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.10;rport;branch=z9hG4bKBpStmeeQU2j1D
   Max-Forwards: 69
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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
   Proxy-Authorization: Digest username="9999", realm="asterisk", nonce="4c256898", algorithm=MD5, uri="sip:401@192.168.2.5", response="63b6418f049af14ff2d44e17d7d72f34"
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 244
   X-Siemens-Call-Type: ST-secure, ST-insecure
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "7777" <sip:7777@192.168.2.5>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1554117544 1554117545 IN IP4 192.168.2.10
   s=FreeSWITCH
   c=IN IP4 192.168.2.10
   t=0 0
   m=audio 32412 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=rtpmap:13 CN/8000
   a=ptime:20
   ------------------------------------------------------------------------
recv 503 bytes from udp/[192.168.2.5]:5060 at 23:19:23.653116:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Content-Length: 0

   ------------------------------------------------------------------------
recv 745 bytes from udp/[192.168.2.5]:5060 at 23:19:24.548759:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Require: timer
   Content-Type: application/sdp
   Content-Length: 182

   v=0
   o=root 23324 23324 IN IP4 192.168.2.5
   s=session
   c=IN IP4 192.168.2.5
   t=0 0
   m=audio 11598 RTP/AVP 8
   a=rtpmap:8 PCMA/8000
   a=silenceSupp:off - - - -
   a=ptime:20
   a=sendrecv
   ------------------------------------------------------------------------
recv 745 bytes from udp/[192.168.2.5]:5060 at 23:19:25.549674:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Require: timer
   Content-Type: application/sdp
   Content-Length: 182

   v=0
   o=root 23324 23324 IN IP4 192.168.2.5
   s=session
   c=IN IP4 192.168.2.5
   t=0 0
   m=audio 11598 RTP/AVP 8
   a=rtpmap:8 PCMA/8000
   a=silenceSupp:off - - - -
   a=ptime:20
   a=sendrecv
   ------------------------------------------------------------------------
recv 745 bytes from udp/[192.168.2.5]:5060 at 23:19:26.550846:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Require: timer
   Content-Type: application/sdp
   Content-Length: 182

   v=0
   o=root 23324 23324 IN IP4 192.168.2.5
   s=session
   c=IN IP4 192.168.2.5
   t=0 0
   m=audio 11598 RTP/AVP 8
   a=rtpmap:8 PCMA/8000
   a=silenceSupp:off - - - -
   a=ptime:20
   a=sendrecv
   ------------------------------------------------------------------------
recv 745 bytes from udp/[192.168.2.5]:5060 at 23:19:28.550669:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Require: timer
   Content-Type: application/sdp
   Content-Length: 182

   v=0
   o=root 23324 23324 IN IP4 192.168.2.5
   s=session
   c=IN IP4 192.168.2.5
   t=0 0
   m=audio 11598 RTP/AVP 8
   a=rtpmap:8 PCMA/8000
   a=silenceSupp:off - - - -
   a=ptime:20
   a=sendrecv
   ------------------------------------------------------------------------
recv 432 bytes from udp/[192.168.2.5]:5060 at 23:19:30.442714:
   ------------------------------------------------------------------------
   BYE sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.5:5060;branch=z9hG4bK2836912f;rport
   From: <sip:401@192.168.2.5>;tag=as10f04bc3
   To: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 102 BYE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Max-Forwards: 70
   Content-Length: 0

   ------------------------------------------------------------------------
2019-04-01 23:19:30.424363 [NOTICE] sofia.c:1079 Hangup sofia/internal/401 [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/401) Running State Change CS_HANGUP (Cur 2 Tot 8)
send 433 bytes to udp/[192.168.2.5]:5060 at 23:19:30.443518:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.5:5060;branch=z9hG4bK2836912f;rport=5060
   From: <sip:401@192.168.2.5>;tag=as10f04bc3
   To: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 102 BYE
   User-Agent: FreeSWITCH
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, path, replaces
   Content-Length: 0

   ------------------------------------------------------------------------
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/401) Callstate Change DOWN -> HANGUP
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/401) State HANGUP
2019-04-01 23:19:30.424363 [DEBUG] mod_sofia.c:449 Channel sofia/internal/401 hanging up, cause: NORMAL_CLEARING
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:60 sofia/internal/401 Standard HANGUP, cause: NORMAL_CLEARING
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/401) State HANGUP going to sleep
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/401) State Change CS_HANGUP -> CS_REPORTING
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/401) Running State Change CS_REPORTING (Cur 2 Tot 8)
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/401) State REPORTING
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:174 sofia/internal/401 Standard REPORTING, cause: NORMAL_CLEARING
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/401) State REPORTING going to sleep
2019-04-01 23:19:30.424363 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/401) State Change CS_REPORTING -> CS_DESTROY
2019-04-01 23:19:30.424363 [DEBUG] switch_core_session.c:1715 Session 8 (sofia/internal/401) Locked, Waiting on external entities
2019-04-01 23:19:30.444362 [DEBUG] switch_ivr_originate.c:3941 Originate Resulted in Error Cause: 16 [NORMAL_CLEARING]
2019-04-01 23:19:30.444362 [NOTICE] switch_core_session.c:1733 Session 8 (sofia/internal/401) Ended
2019-04-01 23:19:30.444362 [NOTICE] switch_core_session.c:1737 Close Channel sofia/internal/401 [CS_DESTROY]
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/401) Running State Change CS_DESTROY (Cur 1 Tot 8)
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/401) State DESTROY
2019-04-01 23:19:30.444362 [DEBUG] mod_sofia.c:354 sofia/internal/401 SOFIA DESTROY
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:181 sofia/internal/401 Standard DESTROY
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/401) State DESTROY going to sleep
2019-04-01 23:19:30.444362 [INFO] mod_dptools.c:3518 Originate Failed.  Cause: NORMAL_CLEARING
2019-04-01 23:19:30.444362 [NOTICE] switch_core_state_machine.c:385 sofia/internal/7777@192.168.2.10:5060 has executed the last dialplan instruction, hanging up.
2019-04-01 23:19:30.444362 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/7777@192.168.2.10:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/7777@192.168.2.10:5060) State EXECUTE going to sleep
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7777@192.168.2.10:5060) Running State Change CS_HANGUP (Cur 1 Tot 8)
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/7777@192.168.2.10:5060) Callstate Change RINGING -> HANGUP
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/7777@192.168.2.10:5060) State HANGUP
2019-04-01 23:19:30.444362 [DEBUG] mod_sofia.c:443 sofia/internal/7777@192.168.2.10:5060 Overriding SIP cause 480 with 200 from the other leg
2019-04-01 23:19:30.444362 [DEBUG] mod_sofia.c:449 Channel sofia/internal/7777@192.168.2.10:5060 hanging up, cause: NORMAL_CLEARING
2019-04-01 23:19:30.444362 [DEBUG] mod_sofia.c:589 Responding to INVITE with: 200
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:60 sofia/internal/7777@192.168.2.10:5060 Standard HANGUP, cause: NORMAL_CLEARING
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/7777@192.168.2.10:5060) State HANGUP going to sleep
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/7777@192.168.2.10:5060) State Change CS_HANGUP -> CS_REPORTING
send 946 bytes to udp/[192.168.2.41]:5060 at 23:19:30.460487:
   ------------------------------------------------------------------------
   SIP/2.0 500 No session set by user
   Via: SIP/2.0/UDP 192.168.2.41;branch=z9hG4bK169fc7e5a6e1207bd;rport=5060
   Max-Forwards: 70
   From: "7777" <sip:7777@192.168.2.10:5060>;tag=c607ca2663;epid=SC0c351a
   To: <sip:401@192.168.2.10:5060>;tag=e9DcrZeSDyceH
   Call-ID: 356f4bddc52d1c96
   CSeq: 2140941627 INVITE
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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
   Reason: Q.850;cause=16;text="NORMAL_CLEARING"
   Content-Length: 0
   Remote-Party-ID: "401" <sip:401@192.168.2.10>;party=calling;privacy=off;screen=no

   ------------------------------------------------------------------------
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7777@192.168.2.10:5060) Running State Change CS_REPORTING (Cur 1 Tot 8)
2019-04-01 23:19:30.444362 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/7777@192.168.2.10:5060) State REPORTING
recv 430 bytes from udp/[192.168.2.41]:5060 at 23:19:30.466346:
   ------------------------------------------------------------------------
   ACK sip:401@192.168.2.10:5060;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.41;branch=z9hG4bK169fc7e5a6e1207bd
   Route: <sip:192.168.2.10:5060;lr;transport=udp>
   Max-Forwards: 70
   From: "7777" <sip:7777@192.168.2.10:5060>;tag=c607ca2663;epid=SC0c351a
   To: <sip:401@192.168.2.10:5060>;tag=e9DcrZeSDyceH
   Call-ID: 356f4bddc52d1c96
   CSeq: 2140941627 ACK
   User-Agent: OpenStage_40_V3 R4.10.0      SIP  160728
   Content-Length: 0

   ------------------------------------------------------------------------
2019-04-01 23:19:30.524364 [DEBUG] switch_core_state_machine.c:174 sofia/internal/7777@192.168.2.10:5060 Standard REPORTING, cause: NORMAL_CLEARING
2019-04-01 23:19:30.524364 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/7777@192.168.2.10:5060) State REPORTING going to sleep
2019-04-01 23:19:30.524364 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/7777@192.168.2.10:5060) State Change CS_REPORTING -> CS_DESTROY
2019-04-01 23:19:30.524364 [DEBUG] switch_core_session.c:1715 Session 7 (sofia/internal/7777@192.168.2.10:5060) Locked, Waiting on external entities
2019-04-01 23:19:30.524364 [NOTICE] switch_core_session.c:1733 Session 7 (sofia/internal/7777@192.168.2.10:5060) Ended
2019-04-01 23:19:30.524364 [NOTICE] switch_core_session.c:1737 Close Channel sofia/internal/7777@192.168.2.10:5060 [CS_DESTROY]
2019-04-01 23:19:30.524364 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/7777@192.168.2.10:5060) Running State Change CS_DESTROY (Cur 0 Tot 8)
2019-04-01 23:19:30.524364 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/7777@192.168.2.10:5060) State DESTROY
2019-04-01 23:19:30.524364 [DEBUG] mod_sofia.c:354 sofia/internal/7777@192.168.2.10:5060 SOFIA DESTROY
2019-04-01 23:19:30.524364 [DEBUG] switch_core_state_machine.c:181 sofia/internal/7777@192.168.2.10:5060 Standard DESTROY
2019-04-01 23:19:30.524364 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/7777@192.168.2.10:5060) State DESTROY going to sleep
recv 729 bytes from udp/[192.168.2.5]:5060 at 23:19:31.421113:
   ------------------------------------------------------------------------
   OPTIONS sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe.OxygenPBX SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.5:5060;branch=z9hG4bK17ff44d5;rport
   From: "unknown" <sip:unknown@192.168.2.5;user=phone>;tag=as44d33727
   To: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   Contact: <sip:unknown@192.168.2.5>
   Call-ID: 766e66790050605e7b4939ee7d1ae247@192.168.2.5
   CSeq: 102 OPTIONS
   User-Agent: fw2016052315-IVV14200.N2UHM
   Max-Forwards: 0
   Date: Mon, 01 Apr 2019 20:19:31 GMT
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces
   Content-Length: 0

   ------------------------------------------------------------------------
send 882 bytes to udp/[192.168.2.5]:5060 at 23:19:31.421408:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.5:5060;branch=z9hG4bK17ff44d5;rport=5060
   From: "unknown" <sip:unknown@192.168.2.5;user=phone>;tag=as44d33727
   To: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>;tag=gU0XUNg07FSKr
   Call-ID: 766e66790050605e7b4939ee7d1ae247@192.168.2.5
   CSeq: 102 OPTIONS
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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-Length: 0

   ------------------------------------------------------------------------
send 1403 bytes to udp/[192.168.2.5]:5060 at 23:19:31.649571:
   ------------------------------------------------------------------------
   INVITE sip:401@192.168.2.5 SIP/2.0
   Via: SIP/2.0/UDP 192.168.2.10;rport;branch=z9hG4bKBpStmeeQU2j1D
   Max-Forwards: 69
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   Contact: <sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe>
   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
   Proxy-Authorization: Digest username="9999", realm="asterisk", nonce="4c256898", algorithm=MD5, uri="sip:401@192.168.2.5", response="63b6418f049af14ff2d44e17d7d72f34"
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 244
   X-Siemens-Call-Type: ST-secure, ST-insecure
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "7777" <sip:7777@192.168.2.5>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1554117544 1554117545 IN IP4 192.168.2.10
   s=FreeSWITCH
   c=IN IP4 192.168.2.10
   t=0 0
   m=audio 32412 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=rtpmap:13 CN/8000
   a=ptime:20
   ------------------------------------------------------------------------
recv 507 bytes from udp/[192.168.2.5]:5060 at 23:19:31.652645:
   ------------------------------------------------------------------------
   SIP/2.0 503 Unavailable
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Require: timer
   Content-Length: 0

   ------------------------------------------------------------------------
recv 745 bytes from udp/[192.168.2.5]:5060 at 23:19:32.549718:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Require: timer
   Content-Type: application/sdp
   Content-Length: 182

   v=0
   o=root 23324 23324 IN IP4 192.168.2.5
   s=session
   c=IN IP4 192.168.2.5
   t=0 0
   m=audio 11598 RTP/AVP 8
   a=rtpmap:8 PCMA/8000
   a=silenceSupp:off - - - -
   a=ptime:20
   a=sendrecv
   ------------------------------------------------------------------------
recv 745 bytes from udp/[192.168.2.5]:5060 at 23:19:36.550696:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.2.10;branch=z9hG4bKBpStmeeQU2j1D;received=192.168.2.10;rport=5060
   From: "7777" <sip:9999@192.168.2.5>;tag=Fj74StZva720c
   To: <sip:401@192.168.2.5>;tag=as10f04bc3
   Call-ID: 43cf1dd4-cf5e-1237-7b9b-0025b328ba78
   CSeq: 2521187 INVITE
   User-Agent: fw2016052315-IVV14200.N2UHM
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Session-Expires: -1;refresher=uac
   Contact: <sip:401@192.168.2.5>
   Require: timer
   Content-Type: application/sdp
   Content-Length: 182

   v=0
   o=root 23324 23324 IN IP4 192.168.2.5
   s=session
   c=IN IP4 192.168.2.5
   t=0 0
   m=audio 11598 RTP/AVP 8
   a=rtpmap:8 PCMA/8000
   a=silenceSupp:off - - - -
   a=ptime:20
   a=sendrecv
   ————————————————————————————————————

And tcpdump trace 

23:25:02.314879 IP6 fe80::dc5a:83fe:4382:a063.52511 > ff02::c.1900: UDP, length 146
23:25:02.350131 IP 192.168.2.10.52081 > oxygen.voip.domain: 12070+ PTR? c.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.2.0.f.f.ip6.arpa. (90)
23:25:02.387056 IP oxygen.voip.domain > 192.168.2.10.52081: 12070 NXDomain 0/1/0 (154)
23:25:02.387158 IP 192.168.2.10.34229 > oxygen.voip.domain: 31187+ PTR? 3.6.0.a.2.8.3.4.e.f.3.8.a.5.c.d.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa. (90)
23:25:02.424498 IP oxygen.voip.domain > 192.168.2.10.34229: 31187 NXDomain* 0/1/0 (154)
23:25:02.424603 IP 192.168.2.10.39283 > oxygen.voip.domain: 10115+ PTR? 5.2.168.192.in-addr.arpa. (42)
23:25:02.426138 IP oxygen.voip.domain > 192.168.2.10.39283: 10115* 1/0/0 PTR oxygen.voip. (67)
23:25:02.426220 IP 192.168.2.10.33703 > oxygen.voip.domain: 53769+ PTR? 10.2.168.192.in-addr.arpa. (43)
23:25:02.688484 IP 7777.lan.sip > 192.168.2.10.sip: SIP: INVITE sip:401@192.168.2.10:5060;transport=udp SIP/2.0
23:25:02.688546 IP 192.168.2.10.52828 > oxygen.voip.domain: 19632+ PTR? 41.2.168.192.in-addr.arpa. (43)
23:25:02.689888 IP oxygen.voip.domain > 192.168.2.10.52828: 19632* 1/0/0 PTR 7777.lan. (65)
23:25:02.695341 IP 192.168.2.10.sip > 7777.lan.sip: SIP: SIP/2.0 100 Trying
23:25:02.723923 IP 192.168.2.10.sip > oxygen.voip.sip: SIP: INVITE sip:401@192.168.2.5 SIP/2.0
23:25:02.727637 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 407 Proxy Authentication Required
23:25:02.727789 IP 192.168.2.10.sip > oxygen.voip.sip: SIP: ACK sip:401@192.168.2.5 SIP/2.0
23:25:02.727946 IP 192.168.2.10.sip > oxygen.voip.sip: SIP: INVITE sip:401@192.168.2.5 SIP/2.0
23:25:02.732641 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 100 Trying
23:25:02.756941 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 180 Ringing
23:25:03.728747 IP 192.168.2.10.sip > oxygen.voip.sip: SIP: INVITE sip:401@192.168.2.5 SIP/2.0
23:25:03.731200 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 100 Trying
23:25:05.728767 IP 192.168.2.10.sip > oxygen.voip.sip: SIP: INVITE sip:401@192.168.2.5 SIP/2.0
23:25:05.731230 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 100 Trying
23:25:08.436779 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 200 OK
23:25:08.446653 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.446678 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:08.466422 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.466437 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:08.486334 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.486347 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:08.487440 STP 802.1d, Config, Flags [none], bridge-id 8000.00:1d:1c:04:d5:ac.8001, length 43
23:25:08.506432 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.506447 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:08.526267 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.526282 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:08.546424 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.546438 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:08.566329 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.586319 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.606286 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.626212 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.646351 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.666312 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.686319 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.706279 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.726197 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.746407 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.766435 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.786410 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.806302 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.826217 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.846282 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.866301 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.886329 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.906297 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.926239 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.946346 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.966305 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:08.986323 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.006257 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.026221 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.046302 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.066320 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.086294 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.106293 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.126197 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.146430 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.166309 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.186300 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.206286 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.226221 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.248148 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.266316 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.286334 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.306288 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.326226 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.346445 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.366325 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.386362 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.406327 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.426218 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.437838 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 200 OK
23:25:09.446354 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.446369 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:09.466410 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.486235 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.506303 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.526276 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.546373 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.566296 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.586274 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.606280 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.626284 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.646287 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.666365 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.686269 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.706309 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.726314 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.728766 IP 192.168.2.10.sip > oxygen.voip.sip: SIP: INVITE sip:401@192.168.2.5 SIP/2.0
23:25:09.731172 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 100 Trying
23:25:09.732357 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 200 OK
23:25:09.746460 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.766311 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.786286 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.806338 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.826311 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.846239 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.866314 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.886412 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.906337 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.926263 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.946425 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.966306 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:09.986324 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.006330 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.026315 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.046240 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.066301 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.086279 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.106327 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.151813 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.152079 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.166330 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.186327 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.206532 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.226295 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.246244 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.266318 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.286333 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.306284 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.326333 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.347574 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.366315 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.386367 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.406282 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.426324 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.437839 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 200 OK
23:25:10.446305 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.446320 IP 192.168.2.10 > oxygen.voip: ICMP 192.168.2.10 udp port 28242 unreachable, length 208
23:25:10.466345 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.486276 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.487434 STP 802.1d, Config, Flags [none], bridge-id 8000.00:1d:1c:04:d5:ac.8001, length 43
23:25:10.506361 IP oxygen.voip.10424 > 192.168.2.10.28242: UDP, length 172
23:25:10.521924 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: BYE sip:gw+af53c152-0f9c-4166-ad4f-3abf497e9abe@192.168.2.10:5060;transport=udp;gw=af53c152-0f9c-4166-ad4f-3abf497e9abe SIP/2.0
23:25:10.522745 IP 192.168.2.10.sip > oxygen.voip.sip: SIP: SIP/2.0 200 OK
23:25:10.533129 IP 192.168.2.10.sip > 7777.lan.sip: SIP: SIP/2.0 500 No session set by user
23:25:10.540064 IP 7777.lan.sip > 192.168.2.10.sip: SIP: ACK sip:401@192.168.2.10:5060;transport=udp SIP/2.0
23:25:12.316170 IP6 fe80::dc5a:83fe:4382:a063.52511 > ff02::c.1900: UDP, length 146
23:25:12.437875 IP oxygen.voip.sip > 192.168.2.10.sip: SIP: SIP/2.0 200 OK
23:25:12.487408 STP 802.1d, Config, Flags [none], bridge-id 8000.00:1d:1c:04:d5:ac.8001, length 43
23:25:14.487284 STP 802.1d, Config, Flags [none], bridge-id 8000.00:1d:1c:04:d5:ac.8001, length 43
23:25:15.902213 IP 192.168.2.10.ntp > ns4.asda.gr.ntp: NTPv4, Client, length 48
23:25:15.902312 IP 192.168.2.10.34483 > oxygen.voip.domain: 23686+ PTR? 194.164.93.193.in-addr.arpa. (45)
23:25:15.903728 IP oxygen.voip.domain > 192.168.2.10.34483: 23686 1/0/0 PTR ns4.asda.gr. (70)
23:25:15.934167 IP ns4.asda.gr.ntp > 192.168.2.10.ntp: NTPv4, Server, length 48
23:25:16.317586 IP6 fe80::dc5a:83fe:4382:a063.52511 > ff02::c.1900: UDP, length 146

[Freeswitch-users] Freeswitch trunk with router vodafone asterisk based

"Δημήτριος Δ. Κάργας"