When the provider sends a 200 OK in response to our INVITE, FreeSWITCH's SIP stack discards the message, and we are unable to determine the cause of this behavior.
I am sharing the SIP message exchange below. Could any SIP experts assist us in understanding why FreeSWITCH is not sending an ACK in response to the 200 OK message?
We understand that the issue is not related to Kamailio but seems to be within the SIP stack or FreeSWITCH itself.
send 1203 bytes to tcp/[10.xxx.x.30]:5060 at 15:48:23.001054:
------------------------------------------------------------------------
INVITE sip:+86xxxxxxxxxxx@35.xxx.xxx.xxx;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 10.xxx.x.10:5080;rport;branch=z9hG4bKD8p0KmvB8ZKma
Max-Forwards: 69
From: "+1xxxxxxxxxx" <sip:+1xxxxxxxxxx@10.xxx.x.10>;tag=ZSvBBtypKypDS
To: <sip:+86xxxxxxxxxxx@35.xxx.xxx.xxx>
Call-ID: 21b7b9b5-b8a5-123d-63a6-ecf4bbd64f6c
CSeq: 85714459 INVITE
Contact: <sip:gw+commsgroup@10.xxx.x.10:5080;transport=tcp;gw=commsgroup>
User-Agent: FreeSWITCH-mod_sofia/1.10.11-release~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: 347
X-FS-Support: update_display,send_info
Remote-Party-ID: "+1xxxxxxxxxx" <sip:+1xxxxxxxxxx@10.xxx.x.10>;party=calling;screen=yes;privacy=off
v=0
o=Z 5395246197255529491 693195311 IN IP4 10.xxx.x.225
s=Z
c=IN IP4 10.xxx.x.225
t=0 0
m=audio 55319 RTP/AVP 106 9 98 101 0 8 3
a=rtpmap:106 opus/48000/2
a=fmtp:106 sprop-maxcapturerate=16000; minptime=20; useinbandfec=1
a=rtpmap:98 telephone-event/48000
a=fmtp:98 0-16
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtcp-mux
tport.c:2316 tport_set_secondary_timer() tport(0x150e6c0291c0): reset timer
nta.c:8400 outgoing_send() nta: sent INVITE (85714459) to tcp/10.xxx.x.30:5060
tport.c:4191 tport_pend() tport_pend(0x150e6c0291c0): pending 0x150e6c0c52f0 for tcp/10.xxx.x.30:5060 (already 2)
nua_session.c:4131 signal_call_state_change() nua(0x150d9c107460): call state changed: init -> calling, sent offer
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x150e6c0c0210, [0x150e91772798], [0x150e91772790], [(nil)]) called
nua_stack.c:299 nua_stack_event() nua(0x150d9c107460): event i_state INVITE sent
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x150d9c107460): entering
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua_stack.c:558 nua_signal() nua(0x150d9c107460): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
2024-07-09 15:48:22.985772 100.00% [DEBUG] sofia.c:7493 Channel sofia/external/+86xxxxxxxxxxx entering state [calling][0]
nua_stack.c:599 nua_stack_signal() nua(0x150d9c107460): recv signal r_handle_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x150d9c107460): entering
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x150d9c107460): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x150d9c107460): recv signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
2024-07-09 15:48:22.985772 100.00% [DEBUG] switch_core_state_machine.c:659 (sofia/external/+86xxxxxxxxxxx) State CONSUME_MEDIA
2024-07-09 15:48:22.985772 100.00% [DEBUG] switch_core_state_machine.c:659 (sofia/external/+86xxxxxxxxxxx) State CONSUME_MEDIA going to sleep
tport.c:2796 tport_wakeup() tport_wakeup(0x150e6c0291c0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x150e6c0291c0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x150e6c0291c0) msg 0x150e6c0cb390 from (tcp/10.xxx.x.30:5060) has 352 bytes, veclen = 1
recv 352 bytes from tcp/[10.xxx.x.30]:5060 at 15:48:23.004029:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.xxx.x.10:5080;rport=37555;branch=z9hG4bKD8p0KmvB8ZKma;received=10.xxx.x.10
From: "+1xxxxxxxxxx" <sip:+1xxxxxxxxxx@10.xxx.x.10>;tag=ZSvBBtypKypDS
To: <sip:+86xxxxxxxxxxx@35.xxx.xxx.xxx>
Call-ID: 21b7b9b5-b8a5-123d-63a6-ecf4bbd64f6c
CSeq: 85714459 INVITE
Server: Kamailio 5.8
Content-Length: 0
tport.c:3055 tport_deliver() tport_deliver(0x150e6c0291c0): msg 0x150e6c0cb390 (352 bytes) from tcp/10.xxx.x.30:5060/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 100 Trying for INVITE (85714459)
nta.c:3445 agent_recv_response() nta: 100 Trying is going to a transaction
nta.c:9697 outgoing_estimate_delay() nta_outgoing: RTT is 3.038 ms
tport.c:4253 tport_release() tport_release(0x150e6c0291c0): 0x150e6c0c52f0 by 0x150e6c0be9a0 with 0x150e6c0cb390 (preliminary)
tport.c:2316 tport_set_secondary_timer() tport(0x150e6c0291c0): reset timer
nta.c:5904 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x150e91973a50)
nta.c:7270 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 0/0 term, 2/3 free
nta.c:1310 agent_timer() nta: timer not set
tport.c:2796 tport_wakeup() tport_wakeup(0x150e6c0291c0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x150e6c0291c0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x150e6c0291c0) msg 0x150e6c0c9d90 from (tcp/10.xxx.x.30:5060) has 1195 bytes, veclen = 1
recv 1195 bytes from tcp/[10.xxx.x.30]:5060 at 15:48:27.070278:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.xxx.x.10:5080;received=10.xxx.x.10;rport=37555;branch=z9hG4bKD8p0Km
From: "+1xxxxxxxxxx" <sip:+1xxxxxxxxxx@10.xxx.x.10>;tag=ZSvBBtypKypDS
To: <sip:+86xxxxxxxxxxx@35.xxx.xxx.xxx>;tag=7bQ0OSl9Dl-39455-57712329-1057554-14
Call-ID: 21b7b9b5-b8a5-123d-63a6-ecf4bbd64f6c
CSeq: 85714459 INVITE
Contact: <sip:127.0.0.8;line=sr-H46V9.fbsXNDlkfEJjGbujK3J4aDo1GzP5GOJ8hcsXwV9LAWw1abHkqWlXczwL7*>
Record-Route: <sip:13x.xxx.xxx.xx;transport=tcp;lr;ftag=ZSvBBtypKypDS;did=cbd.e581;nat=yes>
Supported: sdp-anat
Allow: INVITE,PRACK,ACK,UPDATE,CANCEL,BYE,OPTIONS,INFO,SUBSCRIBE,REFER,NOTIFY
Require: timer
Session-Expires: 2000;refresher=uas
Server: Mediant VE SBC/v.7.40A.250.265
Accept: application/sdp, application/isup, multipart/mixed, application/dtmf, application/dtmf-relay
Content-Type: application/sdp
Content-Length: 219
User-Agent: Kamailio 5.8
v=0
o=- 846721169 1919912032 IN IP4 13x.xxx.xxx.xx
s=-
c=IN IP4 13x.xxx.xxx.xx
t=0 0
m=audio 49708 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:49709
tport.c:3055 tport_deliver() tport_deliver(0x150e6c0291c0): msg 0x150e6c0c9d90 (1195 bytes) from tcp/10.xxx.x.30:5060/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 200 OK for INVITE (85714459)
nta.c:3494 agent_recv_response() nta: 200 OK was discarded
Any insights or suggestions would be greatly appreciated,
Specifically, why is the 200 OK response being discarded?. Thank you!
Regards,
Mustafa