[Freeswitch-users] No ACK for 200 OK

Tue Jun 5 09:30:30 UTC 2018

Dear Michael Jerris,

Thank you for your consideration!

sofia loglevel tport 9
Sofia log level for component [tport] has been set to [9]

Here is the log when the parameter in the gateway was commented out

In the received packet in the header Contact:
<sip:1000@[2a02:7b43:1eb0:eb21::2]:5060;transport=udp>
the selected transport is udp!!! Freeswitch does not sent ACK
As for tport log I do not see any fatal errors in this case, maybe you, as
a specialist, can see?

tport.c:3023 tport_deliver() tport_deliver(0x7f4e6c0ce360): msg
0x7f4e6c0e7520 (1184 bytes) from tls/[2a02:7b43:1eb0:eb21::2]:5061/sips
next=(nil)
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e6c0ce360): reset timer
tport.c:2773 tport_wakeup() tport_wakeup(0x7f4e58096970): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f4e58096970)
tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f4e58096970):
tls_read() returned 4
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f4e58096970) msg
0x7f4e58068390 from (tls/181.201.33.15:39478) has 4 bytes, veclen = 1
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e58096970): reset timer
tport.c:2773 tport_wakeup() tport_wakeup(0x7f4e6c0ce360): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f4e6c0ce360)
tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f4e6c0ce360):
tls_read() returned 845
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f4e6c0ce360) msg
0x7f4e6c0e7520 from (tls/[2a02:7b43:1eb0:eb21::2]:5061) has 845 bytes,
veclen = 1
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e6c0ce360): reset timer
tport.c:2773 tport_wakeup() tport_wakeup(0x7f4e6c0ce360): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f4e6c0ce360)
tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f4e6c0ce360):
tls_read() returned 339
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f4e6c0ce360) msg
0x7f4e6c0e7520 from (tls/[2a02:7b43:1eb0:eb21::2]:5061) has 339 bytes,
veclen = 1
recv 1184 bytes from tls/[2a02:7b43:1eb0:eb21::2]:5061 at 07:05:06.088128:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/TLS
[2a02:f680:e0:1210::2123]:5061;branch=z9hG4bK0UjgcDj2ae5Zr;rport=54911
   From: "Extension 1000" <sip:1000@
[2a02:f680:e0:1210::2123]>;tag=1mKgNjj91mpSe
   To: <sip:1000@[2a02:7b43:1eb0:eb21::2]:5061>;tag=jmg806XU07Dee
   Call-ID: 6368d0a7-e318-1236-2fbf-525400af4b7f
   CSeq: 123749671 INVITE
   Contact: <sip:1000@[2a02:7b43:1eb0:eb21::2]:5060;transport=udp>
   User-Agent:
FreeSWITCH-mod_sofia/1.6.19+git~20171120T163416Z~b1b21d0695~64bit
   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: 339
   X-FS-Display-Name: Outbound Call
   X-FS-Display-Number: sip:1000%@[2a02:7b43:1eb0:eb21::2]
   X-FS-Support: update_display,send_info

   v=0
   o=FreeSWITCH 1528150294 1528150295 IN IP6 2a02:7b43:1eb0:eb21::2
   s=FreeSWITCH
   c=IN IP6 2a02:7b43:1eb0:eb21::2
   t=0 0
   m=audio 21180 RTP/SAVP 8 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   a=crypto:1 AEAD_AES_256_GCM_8
inline:eezgaWEZZUvKciFAkpVWpZhdv39wLjvIyzMZMbmk6FbkYfnrFAm9eziQq5M
   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f4e6c0ce360): msg
0x7f4e6c0e7520 (1184 bytes) from tls/[2a02:7b43:1eb0:eb21::2]:5061/sips
next=(nil)
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e6c0ce360): reset timer
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e6c0ce360): reset timer
tport.c:2773 tport_wakeup() tport_wakeup(0x7f4e58096970): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f4e58096970)
tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f4e58096970):
tls_read() returned 565
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f4e58096970) msg
0x7f4e58068390 from (tls/181.201.33.15:39478) has 565 bytes, veclen = 1
tport.c:3023 tport_deliver() tport_deliver(0x7f4e58096970): msg
0x7f4e58068390 (573 bytes) from tls/181.201.33.15:39478/sips next=(nil)
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e58096970): reset timer
tport.c:3257 tport_tsend() tport_tsend(0x7f4e58096970) tpn = TLS/
181.201.33.15:39478
tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec
0x7f4e58047300 0x7f4e58097d90 628 (628)
tport.c:3594 tport_vsend() tport_vsend(0x7f4e58096970): 628 bytes of 628 to
tls/181.201.33.15:39478
tport.c:3492 tport_send_msg() tport_vsend returned 628
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e58096970): reset timer
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e58096970): reset timer
tport.c:2773 tport_wakeup() tport_wakeup(0x7f4e58096970): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f4e58096970)
tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f4e58096970):
tls_read() returned 562
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f4e58096970) msg
0x7f4e58068390 from (tls/181.201.33.15:39478) has 562 bytes, veclen = 1
tport.c:3023 tport_deliver() tport_deliver(0x7f4e58096970): msg
0x7f4e58068390 (562 bytes) from tls/181.201.33.15:39478/sips next=(nil)
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e58096970): reset timer

===========================================================================
And here is the log when the parameter in the gateway was uncommented
<param name="register-transport" value="tls"/>  and Freeswitch worked
smoothly

recv 1184 bytes from tls/[2a02:7b43:1eb0:eb21::2]:5061 at 07:10:20.917599:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/TLS
[2a02:f680:e0:1210::2123]:5061;branch=z9hG4bKFKyaBgKr06NHH;rport=48779
   From: "Extension 1000" <sip:1000@
[2a02:f680:e0:1210::2123]>;tag=FQ30U1X208Qje
   To: <sip:1000@[2a02:7b43:1eb0:eb21::2]:5061>;tag=prNB8j19mB7rc
   Call-ID: 31efea17-e319-1236-2fbf-525400af4b7f
   CSeq: 123749844 INVITE
   Contact: <sip:1000@[2a02:7b43:1eb0:eb21::2]:5061;transport=tls>
   User-Agent:
FreeSWITCH-mod_sofia/1.6.19+git~20171120T163416Z~b1b21d0695~64bit
   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: 339
   X-FS-Display-Name: Outbound Call
   X-FS-Display-Number: sip:1000%@[2a02:7b43:1eb0:eb21::2]
   X-FS-Support: update_display,send_info

   v=0
   o=FreeSWITCH 1528143500 1528143501 IN IP6 2a02:7b43:1eb0:eb21::2
   s=FreeSWITCH
   c=IN IP6 2a02:7b43:1eb0:eb21::2
   t=0 0
   m=audio 28320 RTP/SAVP 8 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   a=crypto:1 AEAD_AES_256_GCM_8
inline:7/8wl/6LKSgPhTNg1czXmvO1xFiiRNmC4Ugk8MYVqOFe+SMuWdbCWdH6hCE
   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f4e740d82a0): msg
0x7f4e740b6be0 (1184 bytes) from tls/[2a02:7b43:1eb0:eb21::2]:5061/sips
next=(nil)
tport.c:4222 tport_release() tport_release(0x7f4e740d82a0): 0x7f4e740d7590
by 0x7f4e740d2e30 with 0x7f4e740b6be0
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e740d82a0): reset timer
2018-06-05 07:10:20.914430 [INFO] sofia.c:1279 sofia/external-ipv6/1000
Update Callee ID to "Outbound Call" <sip:1000%@[2a02:7b43:1eb0:eb21::2]>
2018-06-05 07:10:20.914430 [DEBUG] sofia.c:7084 Channel
sofia/external-ipv6/1000 entering state [completing][200]
2018-06-05 07:10:20.914430 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1528143500 1528143501 IN IP6 2a02:7b43:1eb0:eb21::2
s=FreeSWITCH
c=IN IP6 2a02:7b43:1eb0:eb21::2
t=0 0
m=audio 28320 RTP/SAVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=crypto:1 AEAD_AES_256_GCM_8
inline:7/8wl/6LKSgPhTNg1czXmvO1xFiiRNmC4Ugk8MYVqOFe+SMuWdbCWdH6hCE

tport.c:4588 tport_by_name() tport(0x7f4e74083b20): found 0x7f4e740d82a0 by
name tls/[2a02:7b43:1eb0:eb21::2]:5061
tport.c:3257 tport_tsend() tport_tsend(0x7f4e740d82a0) tpn =
tls/[2a02:7b43:1eb0:eb21::2]:5061
tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec
0x7f4e74085160 0x7f4e740d8840 483 (483)
tport.c:3594 tport_vsend() tport_vsend(0x7f4e740d82a0): 483 bytes of 483 to
tls/[2a02:7b43:1eb0:eb21::2]:5061
tport.c:3492 tport_send_msg() tport_vsend returned 483
send 483 bytes to tls/[2a02:7b43:1eb0:eb21::2]:5061 at 07:10:20.921598:
   ------------------------------------------------------------------------
   ACK sip:1000@[2a02:7b43:1eb0:eb21::2]:5061;transport=tls SIP/2.0
   Via: SIP/2.0/TLS
[2a02:f680:e0:1210::2123]:5061;branch=z9hG4bKgvQ3cB4UXFc4c
   Max-Forwards: 70
   From: "Extension 1000" <sip:1000@
[2a02:f680:e0:1210::2123]>;tag=FQ30U1X208Qje
   To: <sip:1000@[2a02:7b43:1eb0:eb21::2]:5061>;tag=prNB8j19mB7rc
   Call-ID: 31efea17-e319-1236-2fbf-525400af4b7f
   CSeq: 123749844 ACK
   Contact: <sip:gw+lt_ipv6@
[2a02:f680:e0:1210::2123]:5061;tport=tcp;transport=tls;gw=lt_ipv6>
   Content-Length: 0

   ------------------------------------------------------------------------
tport.c:2296 tport_set_secondary_timer() tport(0x7f4e740d82a0): reset timer

Regards,
Xenia Obolenskaya
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20180605/b173b033/attachment.html>