freeswitch/sofia-sip

FQDN resolution after 200 OK

Opened this issue · 0 comments

syco commented

Hi,

as briefly discussed here https://signalwire-community.slack.com/archives/CDC8H14PN/p1732030504537229 looks like when a GW is configured with an outbound-proxy containing an FQDN it does another dns query before sending the ACK, which could result in the ACK message being sent to the wrong destination.

The GW:

<gateway name="TEST_PROVIDER_1">
  <param name="proxy" value="172.21.10.44"/>
    <param name="outbound-proxy" value="testsbc.domain.com"/>
    <param name="register" value="false"/>
    <param name="caller-id-in-from" value="true"/>
    <param name="extension-in-contact" value="false"/>
  </gateway>
</gateways>

The problem:
image

Trace:

2024/11/19 16:01:23.338355 172.21.10.43:6660 -> 172.21.10.46:6660
INVITE sip:35310000000@172.21.10.44 SIP/2.0
Via: SIP/2.0/UDP 172.21.10.43:6660;rport;branch=z9hG4bKpp18a0m0v4DBg
Max-Forwards: 69
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 91462249 INVITE
Contact: <sip:gw+TEST_PROVIDER_1@172.21.10.43:6660;transport=udp;gw=TEST_PROVIDER_1>
User-Agent: Intellicom Mediagate V4
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: 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: 220
X-cid: eca396e6-14e5-43b2-82f6-b69ba4ad091f
Remote-Party-ID: "Anonymous" <sip:test@172.21.10.43>;party=calling;screen=yes;privacy=off

v=0
o=Intellicom 1732012537 1732012538 IN IP4 172.21.10.43
s=Intellicom
c=IN IP4 172.21.10.43
t=0 0
m=audio 19546 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20


2024/11/19 16:01:23.340498 172.21.10.46:6660 -> 172.21.10.43:6660
SIP/2.0 100 Giving it a try
Via: SIP/2.0/UDP 172.21.10.43:6660;received=172.21.10.43;rport=6660;branch=z9hG4bKpp18a0m0v4DBg
To: <sip:35310000000@172.21.10.44>
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 91462249 INVITE
Server: Intellicom Mediagate V4
Content-Length: 0



2024/11/19 16:01:23.348130 172.21.10.46:6660 -> 172.21.10.43:6660
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 172.21.10.43:6660;received=172.21.10.43;rport=6660;branch=z9hG4bKpp18a0m0v4DBg
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
CSeq: 91462249 INVITE
Server: Intellicom Mediagate V4
Contact: <sip:172.21.10.46:6660;did=c78.37973fd4>
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, INFO, REFER
Content-Length:  0



2024/11/19 16:01:34.361182 172.21.10.46:6660 -> 172.21.10.43:6660
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.21.10.43:6660;received=172.21.10.43;rport=6660;branch=z9hG4bKpp18a0m0v4DBg
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
CSeq: 91462249 INVITE
Server: Intellicom Mediagate V4
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, INFO, REFER
Contact: <sip:172.21.10.46:6660;did=c78.37973fd4>
Supported: 100rel, timer, replaces, norefersub
Content-Type: application/sdp
Content-Length: 243

v=0
o=- 1732012537 1732012540 IN IP4 172.21.10.46
s=-
c=IN IP4 172.21.10.46
t=0 0
m=audio 37966 RTP/AVP 8 101
a=maxptime:140
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
a=rtcp:37967
a=ptime:20


2024/11/19 16:01:34.363037 172.21.10.43:6660 -> 172.21.10.47:6660
ACK sip:172.21.10.46:6660;did=c78.37973fd4 SIP/2.0
Via: SIP/2.0/UDP 172.21.10.43:6660;rport;branch=z9hG4bKQZt1cU53SD4XB
Max-Forwards: 70
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 91462249 ACK
Contact: <sip:gw+TEST_PROVIDER_1@172.21.10.43:6660;transport=udp;gw=TEST_PROVIDER_1>
Content-Length: 0



2024/11/19 16:01:34.860427 172.21.10.46:6660 -> 172.21.10.43:6660
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.21.10.43:6660;received=172.21.10.43;rport=6660;branch=z9hG4bKpp18a0m0v4DBg
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
CSeq: 91462249 INVITE
Server: Intellicom Mediagate V4
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, INFO, REFER
Contact: <sip:172.21.10.46:6660;did=c78.37973fd4>
Supported: 100rel, timer, replaces, norefersub
Content-Type: application/sdp
Content-Length: 243

v=0
o=- 1732012537 1732012540 IN IP4 172.21.10.46
s=-
c=IN IP4 172.21.10.46
t=0 0
m=audio 37966 RTP/AVP 8 101
a=maxptime:140
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
a=rtcp:37967
a=ptime:20


2024/11/19 16:01:34.860616 172.21.10.43:6660 -> 172.21.10.47:6660
ACK sip:172.21.10.46:6660;did=c78.37973fd4 SIP/2.0
Via: SIP/2.0/UDP 172.21.10.43:6660;rport;branch=z9hG4bKQZt1cU53SD4XB
Max-Forwards: 70
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 91462249 ACK
Contact: <sip:gw+TEST_PROVIDER_1@172.21.10.43:6660;transport=udp;gw=TEST_PROVIDER_1>
Content-Length: 0



2024/11/19 16:01:35.861404 172.21.10.46:6660 -> 172.21.10.43:6660
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.21.10.43:6660;received=172.21.10.43;rport=6660;branch=z9hG4bKpp18a0m0v4DBg
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
CSeq: 91462249 INVITE
Server: Intellicom Mediagate V4
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, INFO, REFER
Contact: <sip:172.21.10.46:6660;did=c78.37973fd4>
Supported: 100rel, timer, replaces, norefersub
Content-Type: application/sdp
Content-Length: 243

v=0
o=- 1732012537 1732012540 IN IP4 172.21.10.46
s=-
c=IN IP4 172.21.10.46
t=0 0
m=audio 37966 RTP/AVP 8 101
a=maxptime:140
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
a=rtcp:37967
a=ptime:20


2024/11/19 16:01:35.861585 172.21.10.43:6660 -> 172.21.10.47:6660
ACK sip:172.21.10.46:6660;did=c78.37973fd4 SIP/2.0
Via: SIP/2.0/UDP 172.21.10.43:6660;rport;branch=z9hG4bKQZt1cU53SD4XB
Max-Forwards: 70
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 91462249 ACK
Contact: <sip:gw+TEST_PROVIDER_1@172.21.10.43:6660;transport=udp;gw=TEST_PROVIDER_1>
Content-Length: 0



2024/11/19 16:01:37.861937 172.21.10.46:6660 -> 172.21.10.43:6660
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.21.10.43:6660;received=172.21.10.43;rport=6660;branch=z9hG4bKpp18a0m0v4DBg
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
CSeq: 91462249 INVITE
Server: Intellicom Mediagate V4
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, INFO, REFER
Contact: <sip:172.21.10.46:6660;did=c78.37973fd4>
Supported: 100rel, timer, replaces, norefersub
Content-Type: application/sdp
Content-Length: 243

v=0
o=- 1732012537 1732012540 IN IP4 172.21.10.46
s=-
c=IN IP4 172.21.10.46
t=0 0
m=audio 37966 RTP/AVP 8 101
a=maxptime:140
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
a=rtcp:37967
a=ptime:20


2024/11/19 16:01:37.862139 172.21.10.43:6660 -> 172.21.10.47:6660
ACK sip:172.21.10.46:6660;did=c78.37973fd4 SIP/2.0
Via: SIP/2.0/UDP 172.21.10.43:6660;rport;branch=z9hG4bKQZt1cU53SD4XB
Max-Forwards: 70
From: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
To: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 91462249 ACK
Contact: <sip:gw+TEST_PROVIDER_1@172.21.10.43:6660;transport=udp;gw=TEST_PROVIDER_1>
Content-Length: 0



2024/11/19 16:02:06.361212 172.21.10.46:6660 -> 172.21.10.43:6660
BYE sip:gw+TEST_PROVIDER_1@172.21.10.43:6660;transport=udp;gw=TEST_PROVIDER_1 SIP/2.0
Via: SIP/2.0/UDP 172.21.10.46:6660;branch=z9hG4bK077d.750f8a55.0
From: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
To: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 11403 BYE
Max-Forwards: 68
User-Agent: Intellicom Mediagate V4
Content-Length:  0



2024/11/19 16:02:06.364939 172.21.10.43:6660 -> 172.21.10.46:6660
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.21.10.46:6660;branch=z9hG4bK077d.750f8a55.0
From: <sip:35310000000@172.21.10.44>;tag=68d8e128-ca55-494a-b790-ee689a9a84f0
To: "Anonymous" <sip:test@172.21.10.43>;tag=851gtKg0QrSKc
Call-ID: 5d87baa0-2132-123e-0087-26e781cbb8f1
CSeq: 11403 BYE
User-Agent: Intellicom Mediagate V4
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
Content-Length: 0

Sofia logs:

+OK log level 0 [0]
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8063060 from (udp/172.21.10.43:6660) has 1927 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8063060 (1927 bytes) from udp/172.21.10.45:6660/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received INVITE sip:35310000000@172.21.10.43:6660 SIP/2.0 (CSeq 26219) (load: 0 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:35310000000@172.21.10.43:6660 with contact
nta.c:3164 agent_recv_request() nta: INVITE (26219) going to a default leg
nua_dialog.c:336 nua_dialog_usage_add() nua(0x7f6bd80161a0): adding session usage
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_invite 100 Trying
nua_session.c:4131 signal_call_state_change() nua(0x7f6bd80161a0): call state changed: init -> received, received offer
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_state 100 Trying
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_respond
nua_stack.c:601 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_respond 100 Trying
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = UDP/172.21.10.45:5060
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name UDP/172.21.10.45:5060
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 384 bytes of 384 to udp/172.21.10.45:5060
nta.c:6879 incoming_reply() nta: sent 100 Trying for INVITE (26219)
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_invite
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_invite
nua_dialog.c:336 nua_dialog_usage_add() nua(0x7f6b9400b450): adding session usage
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x7f6bd8057110, soa_generate_offer): called
soa_static.c:1188 offer_answer_step() soa_static(0x7f6bd8057110, soa_generate_offer): generating local description
soa_static.c:1216 offer_answer_step() soa_static(0x7f6bd8057110, soa_generate_offer): upgrade with local description
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f6c059287d0, (nil), ""): called
soa_static.c:1445 offer_answer_step() soa_static(0x7f6bd8057110, soa_generate_offer): storing local description
nta.c:2716 nta_tpn_by_url() nta: selecting scheme sip
nta.c:10941 outgoing_query_a() nta: for "testsbc.domain.com" query "testsbc.domain.com" A
sres.c:2827 sres_send_dns_query() sres_send_dns_query(0x7f6bd8003020, 0x7f6bd8029230) id=14408 A testsbc.domain.com (to [8.8.8.8]:53)
nua_session.c:4131 signal_call_state_change() nua(0x7f6b9400b450): call state changed: init -> calling, sent offer
nua_stack.c:299 nua_stack_event() nua(0x7f6b9400b450): event i_state INVITE sent
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
sres.c:3581 sres_log_response() sres_resolver_receive(0x7f6bd8003020, 0x7f6bd8029230) id=14408 (from [8.8.8.8]:53)
nta.c:11000 outgoing_answer_a() nta: testsbc.domain.com. IN A 172.21.10.46
nta.c:11002 outgoing_answer_a() nta(0x7f6bd8040f80):  A 172.21.10.47
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = */172.21.10.46:6660
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name */172.21.10.46:6660
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 1176 bytes of 1176 to udp/172.21.10.46:6660
nta.c:8400 outgoing_send() nta: sent INVITE (91462249) to */172.21.10.46:6660
tport.c:4191 tport_pend() tport_pend(0x7f6bd8004ed0): pending 0x7f6bd8071140 for udp/172.21.10.43:6660 (already 0)
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd801f020 from (udp/172.21.10.43:6660) has 353 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd801f020 (353 bytes) from udp/172.21.10.46:6660/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 100 Giving it a try for INVITE (91462249)
nta.c:3445 agent_recv_response() nta: 100 Giving it a try is going to a transaction
nta.c:9697 outgoing_estimate_delay() nta_outgoing: RTT is 2.284 ms
tport.c:4253 tport_release() tport_release(0x7f6bd8004ed0): 0x7f6bd8071140 by 0x7f6bd8040f80 with 0x7f6bd801f020 (preliminary)
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd801f020 from (udp/172.21.10.43:6660) has 554 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd801f020 (554 bytes) from udp/172.21.10.46:6660/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 180 Ringing for INVITE (91462249)
nta.c:3445 agent_recv_response() nta: 180 Ringing is going to a transaction
tport.c:4253 tport_release() tport_release(0x7f6bd8004ed0): 0x7f6bd8071140 by 0x7f6bd8040f80 with 0x7f6bd801f020 (preliminary)
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event r_invite 180 Ringing
nua_session.c:4131 signal_call_state_change() nua(0x7f6b9400b450): call state changed: calling -> proceeding
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event i_state 180 Ringing
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
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
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_respond
nua_stack.c:601 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_respond 180 Ringing
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = UDP/172.21.10.45:5060
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name UDP/172.21.10.45:5060
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 797 bytes of 797 to udp/172.21.10.45:5060
nta.c:6879 incoming_reply() nta: sent 180 Ringing for INVITE (26219)
nua_session.c:4131 signal_call_state_change() nua(0x7f6bd80161a0): call state changed: received -> early
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_state 180 Ringing
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
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
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8027bd0 from (udp/172.21.10.43:6660) has 872 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8027bd0 (872 bytes) from udp/172.21.10.46:6660/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 200 OK for INVITE (91462249)
nta.c:3445 agent_recv_response() nta: 200 OK is going to a transaction
tport.c:4253 tport_release() tport_release(0x7f6bd8004ed0): 0x7f6bd8071140 by 0x7f6bd8040f80 with 0x7f6bd8027bd0
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x7f6bd8057110, soa_process_answer): called
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f6bd809aee0, 0x7f6bd80351d0, ""): called
soa_static.c:1303 offer_answer_step() soa_static(0x7f6bd8057110, soa_process_answer): upgrade codecs with remote description
nua_session.c:985 nua_session_client_response() nua(0x7f6b9400b450): INVITE: processed SDP answer in 200 OK (200)
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event r_invite 200 OK
nua_session.c:4131 signal_call_state_change() nua(0x7f6b9400b450): call state changed: proceeding -> completing, received answer
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event i_state 200 OK
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
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
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_ack
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_ack
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nta.c:2716 nta_tpn_by_url() nta: selecting scheme sip
nta.c:10941 outgoing_query_a() nta: for "testsbc.domain.com" query "testsbc.domain.com" A (cached)
nta.c:11000 outgoing_answer_a() nta: testsbc.domain.com. IN A 172.21.10.47
nta.c:11002 outgoing_answer_a() nta(0x7f6bd8026d60):  A 172.21.10.46
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = */172.21.10.47:6660
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name */172.21.10.47:6660
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 458 bytes of 458 to udp/172.21.10.47:6660
nta.c:8400 outgoing_send() nta: sent ACK (91462249) to */172.21.10.47:6660
nua_session.c:4131 signal_call_state_change() nua(0x7f6b9400b450): call state changed: completing -> ready
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event i_state 200 ACK sent
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event i_active 200 Call active
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
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
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_respond
nua_stack.c:601 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_respond 200 OK
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x7f6bd80354f0, soa_generate_answer): called
soa_static.c:1188 offer_answer_step() soa_static(0x7f6bd80354f0, soa_generate_answer): generating local description
soa_static.c:1229 offer_answer_step() soa_static(0x7f6bd80354f0, soa_generate_answer): upgrade with remote description
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f6c059287e0, 0x7f6bd806a810, ""): called
soa_static.c:1445 offer_answer_step() soa_static(0x7f6bd80354f0, soa_generate_answer): storing local description
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = UDP/172.21.10.45:5060
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name UDP/172.21.10.45:5060
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 1050 bytes of 1050 to udp/172.21.10.45:5060
nta.c:6879 incoming_reply() nta: sent 200 OK for INVITE (26219)
nua_session.c:4131 signal_call_state_change() nua(0x7f6bd80161a0): call state changed: early -> completed, sent answer
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_state 200 OK
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8035b20 from (udp/172.21.10.43:6660) has 450 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8035b20 (450 bytes) from udp/172.21.10.45:6660/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received ACK sip:35310000000@172.21.10.43:6660;transport=udp SIP/2.0 (CSeq 26219) (load: 0 rps)
nta.c:3097 agent_recv_request() nta: ACK (26219) is going to INVITE (26219)
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_ack 200 OK
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_session.c:4131 signal_call_state_change() nua(0x7f6bd80161a0): call state changed: completed -> ready
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_state 200 OK
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_active 200 Call active
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
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
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
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
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
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
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8059420 from (udp/172.21.10.43:6660) has 872 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8059420 (872 bytes) from udp/172.21.10.46:6660/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 200 OK for INVITE (91462249)
nta.c:3445 agent_recv_response() nta: 200 OK is going to a transaction
nta.c:9697 outgoing_estimate_delay() nta_outgoing: RTT is 497.586 ms
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = */172.21.10.47:6660
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name */172.21.10.47:6660
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 458 bytes of 458 to udp/172.21.10.47:6660
nta.c:8400 outgoing_send() nta: resent ACK (91462249) to */172.21.10.47:6660
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8059420 from (udp/172.21.10.43:6660) has 872 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8059420 (872 bytes) from udp/172.21.10.46:6660/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 200 OK for INVITE (91462249)
nta.c:3445 agent_recv_response() nta: 200 OK is going to a transaction
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = */172.21.10.47:6660
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name */172.21.10.47:6660
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 458 bytes of 458 to udp/172.21.10.47:6660
nta.c:8400 outgoing_send() nta: resent ACK (91462249) to */172.21.10.47:6660
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8059420 from (udp/172.21.10.43:6660) has 872 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8059420 (872 bytes) from udp/172.21.10.46:6660/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 200 OK for INVITE (91462249)
nta.c:3445 agent_recv_response() nta: 200 OK is going to a transaction
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = */172.21.10.47:6660
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name */172.21.10.47:6660
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 458 bytes of 458 to udp/172.21.10.47:6660
nta.c:8400 outgoing_send() nta: resent ACK (91462249) to */172.21.10.47:6660
nta.c:7223 _nta_incoming_timer() nta: timer I fired, terminate 200 response
nta.c:7270 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
freeswitch@test-mg> 
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8059420 from (udp/172.21.10.43:6660) has 437 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8059420 (437 bytes) from udp/172.21.10.46:6660/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received BYE sip:gw+TEST_PROVIDER_1@172.21.10.43:6660;transport=udp;gw=TEST_PROVIDER_1 SIP/2.0 (CSeq 11403) (load: 0 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:gw+TEST_PROVIDER_1@172.21.10.43:6660 with contact
nta.c:3139 agent_recv_request() nta: BYE (11403) going to existing leg
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event i_bye 100 Trying
nta.c:9233 outgoing_timer_dk() nta: timer D fired, terminate INVITE (91462249)
nta.c:9045 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/1 term, 1/2 free
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nta.c:9112 outgoing_timer_bf() nta: timer F fired, terminating ACK (91462249)
nta.c:9045 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout, 0/0 term, 1/1 free
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_respond
nua_stack.c:601 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_respond 200 OK
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_destroy
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = UDP/172.21.10.46:6660
nua_stack.c:558 nua_signal() nua(0x7f6b9400b450): sent signal r_handle_unref
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name UDP/172.21.10.46:6660
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 478 bytes of 478 to udp/172.21.10.46:6660
nta.c:6879 incoming_reply() nta: sent 200 OK for BYE (11403)
nua_dialog.c:395 nua_dialog_usage_remove_at() nua(0x7f6b9400b450): removing session usage
nua_session.c:4131 signal_call_state_change() nua(0x7f6b9400b450): call state changed: ready -> terminated
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event i_state 200 Session Terminated
nua_stack.c:301 nua_stack_event() nua(0x7f6b9400b450): event i_terminated 200 Session Terminated
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_destroy
nua_stack.c:599 nua_stack_signal() nua(0x7f6b9400b450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_bye
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_bye
nta.c:2716 nta_tpn_by_url() nta: selecting scheme sip
tport.c:3290 tport_tsend() tport_tsend(0x7f6bd8004ed0) tpn = */172.21.10.45:5060
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x7f6bd8004ed0): not found by name */172.21.10.45:5060
tport.c:3625 tport_vsend() tport_vsend(0x7f6bd8004ed0): 593 bytes of 593 to udp/172.21.10.45:5060
nta.c:8400 outgoing_send() nta: sent BYE (91462271) to */172.21.10.45:5060
tport.c:4191 tport_pend() tport_pend(0x7f6bd8004ed0): pending 0x7f6bd8029480 for udp/172.21.10.43:6660 (already 0)
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7f6bd8004ed0): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x7f6bd8004ed0)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x7f6bd8004ed0) msg 0x7f6bd8027bd0 from (udp/172.21.10.43:6660) has 380 bytes, veclen = 1
tport.c:3055 tport_deliver() tport_deliver(0x7f6bd8004ed0): msg 0x7f6bd8027bd0 (380 bytes) from udp/172.21.10.45:6660/sip next=(nil)
nta.c:3378 agent_recv_response() nta: received 200 OK for BYE (91462271)
nta.c:3445 agent_recv_response() nta: 200 OK is going to a transaction
nta.c:9697 outgoing_estimate_delay() nta_outgoing: RTT is 0.629 ms
tport.c:4253 tport_release() tport_release(0x7f6bd8004ed0): 0x7f6bd8029480 by 0x7f6bd8055320 with 0x7f6bd8027bd0
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event r_bye 200 OK
nua_session.c:4131 signal_call_state_change() nua(0x7f6bd80161a0): call state changed: terminating -> terminated
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_state 200 to BYE
nua_stack.c:301 nua_stack_event() nua(0x7f6bd80161a0): event i_terminated 200 to BYE
nua_dialog.c:395 nua_dialog_usage_remove_at() nua(0x7f6bd80161a0): removing session usage
nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0x7f6bd80161a0
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_destroy
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_destroy
nua_stack.c:558 nua_signal() nua(0x7f6bd80161a0): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7f6bd80161a0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nta.c:9233 outgoing_timer_dk() nta: timer K fired, terminate BYE (91462271)
nta.c:9045 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
nta.c:7248 _nta_incoming_timer() nta: timer J fired, terminate 200 response
nta.c:7270 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free