Background mode - PID=[19385]
uac_telephone_event_check .........................................nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_set_params
nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x615000090400, ...) called
nua_stack.c:301 nua_stack_event() nua((nil)): event r_set_params 200 OK
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_set_params
nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x6150000a0580, ...) called
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:559 nua_signal() nua((nil)): sent signal r_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:559 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((nil)): recv signal r_unref
nua_stack.c:301 nua_stack_event() nua((nil)): event r_set_params 200 OK
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:559 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:559 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((nil)): recv signal r_unref
sipp 172.18.0.2:5080 -nr -p 5062 -m 1 -s 1212121212 -recv_timeout 10000 -timeout 10s -sf sipp-scenarios/uac_telephone_event.xml -bg 
2022-06-23 05:39:35.916922 96.67% [WARNING] switch_core.c:3543 Exit status (25344): sipp 172.18.0.2:5080 -nr -p 5062 -m 1 -s 1212121212 -recv_timeout 10000 -timeout 10s -sf sipp-scenarios/uac_telephone_event.xml -bg 
tport.c:2770 tport_wakeup_pri() tport_wakeup_pri(0x6160000b1f80): events IN
tport.c:2893 tport_recv_event() tport_recv_event(0x6160000b1f80)
tport.c:3234 tport_recv_iovec() tport_recv_iovec(0x6160000b1f80) msg 0x619000331880 from (udp/172.18.0.2:5080) has 667 bytes, veclen = 1
recv 667 bytes from udp/[172.18.0.2]:5062 at 05:39:36.081659:
------------------------------------------------------------------------
INVITE sip:1212121212@172.18.0.2:5080 SIP/2.0
Via: SIP/2.0/UDP 172.18.0.2:5062;branch=z9hG4bK-19369-1-0
From: t_sipp <sip:t_sipp@172.18.0.2:5062>;tag=1
To: sut <sip:1212121212@172.18.0.2:5080>
Call-ID: 1-19369@172.18.0.2
CSeq: 1 INVITE
Contact: sip:t_sipp@172.18.0.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Type: application/sdp
Content-Length:   287

v=0
o=user1 53655765 2353687637 IN IP4 172.18.0.2
s=-
c=IN IP4 172.18.0.2
t=0 0
m=audio 6000 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-11,16
a=sendrecv
a=ptime:20

tport.c:3052 tport_deliver() tport_deliver(0x6160000b1f80): msg 0x619000331880 (667 bytes) from udp/172.18.0.2:5080/sip next=(nil)
nta.c:2951 agent_recv_request() nta: received INVITE sip:1212121212@172.18.0.2:5080 SIP/2.0 (CSeq 1) (load: 1 rps)
nta.c:3319 agent_aliases() nta: canonizing sip:1212121212@172.18.0.2:5080 with contact
nta.c:3156 agent_recv_request() nta: INVITE (1) going to a default leg
nta.c:1360 set_timeout() nta: timer shortened to 200 ms
nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
nua_stack.c:935 nh_create() nua: nh_create: entering
nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:280 soa_clone() soa_clone(static::0x615000090400, 0x60600003c020, 0x6120001228c0) called
soa.c:403 soa_set_params() soa_set_params(static::0x615000090900, ...) called
nta.c:4488 nta_leg_tcreate() nta_leg_tcreate(0x60c000080740)
soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x615000090900) called
soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x615000090900, (nil), 0x6170000c1b7c, 287) called
nua_dialog.c:338 nua_dialog_usage_add() nua(0x6120001228c0): adding session usage
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_invite 100 Trying
nua_session.c:4140 signal_call_state_change() nua(0x6120001228c0): call state changed: init -> received, received offer
soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x615000090900, [0x7f61ea812398], [0x7f61ea812390], [(nil)]) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 100 Trying
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:341 nua_handle_bind() nua: nua_handle_bind: entering
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.078101 96.67% [NOTICE] switch_channel.c:1123 New Channel sofia/external/t_sipp@172.18.0.2:5062 [4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67]
nua.c:1164 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.195753 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.18.0.2:5062) Running State Change CS_NEW (Cur 1 Tot 1)
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.195753 96.67% [INFO] sofia.c:10462 sofia/external/t_sipp@172.18.0.2:5062 receiving invite from 172.18.0.2:5062 version: 1.10.8-dev git 33eb8dc 2022-06-23 05:25:40Z 64bit call-id: 1-19369@172.18.0.2
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1164 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:559 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref
nua_stack.c:559 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:559 nua_signal() nua((nil)): sent signal r_unref
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.195753 96.67% [DEBUG] switch_core_state_machine.c:600 (sofia/external/t_sipp@172.18.0.2:5062) State NEW
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:559 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1164 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:559 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:559 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] sofia.c:7499 Channel sofia/external/t_sipp@172.18.0.2:5062 entering state [received][100]
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] sofia.c:7509 Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 172.18.0.2
s=-
c=IN IP4 172.18.0.2
t=0 0
m=audio 6000 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-11,16
a=ptime:20

4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] sofia.c:7912 (sofia/external/t_sipp@172.18.0.2:5062) State Change CS_NEW -> CS_INIT
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1164 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:559 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:559 nua_signal() nua((nil)): sent signal r_unref
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.18.0.2:5062) Running State Change CS_INIT (Cur 1 Tot 1)
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@172.18.0.2:5062) State INIT
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] mod_sofia.c:97 sofia/external/t_sipp@172.18.0.2:5062 SOFIA INIT
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] switch_core_state_machine.c:40 sofia/external/t_sipp@172.18.0.2:5062 Standard INIT
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] switch_core_state_machine.c:48 (sofia/external/t_sipp@172.18.0.2:5062) State Change CS_INIT -> CS_ROUTING
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.216903 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@172.18.0.2:5062) State INIT going to sleep
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.253072 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.18.0.2:5062) Running State Change CS_ROUTING (Cur 1 Tot 1)
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.253072 96.67% [DEBUG] switch_channel.c:2380 (sofia/external/t_sipp@172.18.0.2:5062) Callstate Change DOWN -> RINGING
nta.c:7112 _nta_incoming_timer() nta: timer N1 fired, but avoided sending 100 Trying
nta.c:1308 agent_timer() nta: timer set next to 3208 ms
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.336905 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@172.18.0.2:5062) State ROUTING
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:603 nua_stack_signal() nua(0x6120001228c0): recv signal r_respond 100 Trying
nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x615000090900, ...) called
tport.c:3286 tport_tsend() tport_tsend(0x6160000b1f80) tpn = UDP/172.18.0.2:5062
tport.c:4075 tport_resolve() tport_resolve addrinfo = 172.18.0.2:5062
tport.c:4709 tport_by_addrinfo() tport_by_addrinfo(0x6160000b1f80): not found by name UDP/172.18.0.2:5062
tport.c:3623 tport_vsend() tport_vsend(0x6160000b1f80): 259 bytes of 259 to udp/172.18.0.2:5062
tport.c:3521 tport_send_msg() tport_vsend returned 259
send 259 bytes to udp/[172.18.0.2]:5062 at 05:39:36.347742:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.18.0.2:5062;branch=z9hG4bK-19369-1-0
From: t_sipp <sip:t_sipp@172.18.0.2:5062>;tag=1
To: sut <sip:1212121212@172.18.0.2:5080>
Call-ID: 1-19369@172.18.0.2
CSeq: 1 INVITE
User-Agent: Unit Test
Content-Length: 0


nta.c:6868 incoming_reply() nta: sent 100 Trying for INVITE (1)
nua_stack.c:559 nua_signal() nua(0x6120001228c0): sent signal r_respond
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.436902 96.67% [DEBUG] mod_sofia.c:158 sofia/external/t_sipp@172.18.0.2:5062 SOFIA ROUTING
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.436902 96.67% [DEBUG] switch_core_state_machine.c:230 sofia/external/t_sipp@172.18.0.2:5062 Standard ROUTING
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.436902 96.67% [INFO] mod_dialplan_xml.c:639 Processing t_sipp <t_sipp>->1212121212 in context default
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 Dialplan: sofia/external/t_sipp@172.18.0.2:5062 parsing [default->unit_test_sipp-uac-digest-leak] continue=false
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 Dialplan: sofia/external/t_sipp@172.18.0.2:5062 Regex (FAIL) [unit_test_sipp-uac-digest-leak] destination_number(1212121212) =~ /^\+15553334444$/ break=on-false
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 Dialplan: sofia/external/t_sipp@172.18.0.2:5062 parsing [default->sipp_telephone_check] continue=false
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 Dialplan: sofia/external/t_sipp@172.18.0.2:5062 Regex (PASS) [sipp_telephone_check] destination_number(1212121212) =~ /^1212121212$/ break=on-false
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 Dialplan: sofia/external/t_sipp@172.18.0.2:5062 Action answer() 
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 Dialplan: sofia/external/t_sipp@172.18.0.2:5062 Action set(park_after_bridge=true) 
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 Dialplan: sofia/external/t_sipp@172.18.0.2:5062 Action park() 
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.456901 96.67% [DEBUG] switch_core_state_machine.c:281 (sofia/external/t_sipp@172.18.0.2:5062) State Change CS_ROUTING -> CS_EXECUTE
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.456901 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@172.18.0.2:5062) State ROUTING going to sleep
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.456901 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.18.0.2:5062) Running State Change CS_EXECUTE (Cur 1 Tot 1)
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.476900 96.67% [DEBUG] switch_core_state_machine.c:647 (sofia/external/t_sipp@172.18.0.2:5062) State EXECUTE
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.476900 96.67% [DEBUG] mod_sofia.c:213 sofia/external/t_sipp@172.18.0.2:5062 SOFIA EXECUTE
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.476900 96.67% [DEBUG] switch_core_state_machine.c:323 sofia/external/t_sipp@172.18.0.2:5062 Standard EXECUTE
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 EXECUTE [depth=0] sofia/external/t_sipp@172.18.0.2:5062 answer()
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.496902 96.67% [DEBUG] switch_core_media.c:5653 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.496902 96.67% [DEBUG] switch_core_media.c:5653 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.496902 96.67% [DEBUG] switch_core_media.c:5708 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.496902 96.67% [DEBUG] switch_core_media.c:5653 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.496902 96.67% [DEBUG] switch_core_media.c:5569 Set telephone-event payload to 101@8000
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.496902 96.67% [DEBUG] switch_core_media.c:3873 Set Codec sofia/external/t_sipp@172.18.0.2:5062 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.496902 96.67% [DEBUG] switch_core_codec.c:111 sofia/external/t_sipp@172.18.0.2:5062 Original read codec set to PCMU:0
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.576921 96.67% [DEBUG] switch_core_media.c:5918 Set teAddressSanitizer:DEADLYSIGNAL
=================================================================
==18018==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000000 (pc 0x7f61f156d88b bp 0x7ffd289640e0 sp 0x7ffd28963850 T0)
==18018==The signal is caused by a READ memory access.
==18018==Hint: address points to the zero page.
    #0 0x7f61f156d88a  (/usr/lib/x86_64-linux-gnu/libasan.so.5+0xb488a)
lephone-event payload to 101@8000
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.596923 96.67% [DEBUG] switch_core_media.c:5976 sofia/external/t_sipp@172.18.0.2:5062 Set 2833 dtmf send payload to 101 recv payload to 101
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.596923 96.67% [DEBUG] switch_core_media.c:8778 AUDIO RTP [sofia/external/t_sipp@172.18.0.2:5062] 172.18.0.2 port 29256 -> 172.18.0.2 port 6000 codec: 0 ms: 20
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.633206 96.67% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.633206 96.67% [DEBUG] switch_core_media.c:9090 sofia/external/t_sipp@172.18.0.2:5062 Set 2833 dtmf send payload to 101
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.633206 96.67% [DEBUG] switch_core_media.c:9097 sofia/external/t_sipp@172.18.0.2:5062 Set 2833 dtmf receive payload to 101
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.633206 96.67% [DEBUG] switch_core_media.c:9120 sofia/external/t_sipp@172.18.0.2:5062 Set rtp dtmf delay to 40
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:36.633206 96.67% [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/t_sipp@172.18.0.2:5062!
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:37.474734 93.33% [DEBUG] switch_channel.c:3622 (sofia/external/t_sipp@172.18.0.2:5062) Callstate Change RINGING -> EARLY
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:37.474734 93.33% [DEBUG] switch_core_media.c:8760 Audio params are unchanged for sofia/external/t_sipp@172.18.0.2:5062.
4a9c7d36-98fe-45ae-a9e4-43af2a9ccb67 2022-06-23 05:39:37.474734 93.33% [DEBUG] mod_sofia.c:913 Local SDP sofia/external/t_sipp@172.18.0.2:5062:
v=0
o=FS 1655933521 1655933522 IN IP4 172.18.0.2
s=FS
c=IN IP4 172.18.0.2
t=0 0
m=audio 29256 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv

nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:559 nua_signal() nua(0x6120001228c0): sent signal r_respond
nua_stack.c:603 nua_stack_signal() nua(0x6120001228c0): recv signal r_respond 200 OK
nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x615000090900, ...) called
soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x615000090900, (nil), 0x6170001107ce, -1) called
soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x615000090900, (nil), 0x6170001107ce, -1) called
nua_session.c:2318 nua_invite_server_respond() nua: nua_invite_server_respond: entering
soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x615000090900) called
soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x615000090900, soa_generate_answer): called
soa_static.c:1189 offer_answer_step() soa_static(0x615000090900, soa_generate_answer): generating local description
soa_static.c:1230 offer_answer_step() soa_static(0x615000090900, soa_generate_answer): upgrade with remote description
soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f61ea812730, 0x618000110880, ""): called
soa_static.c:1446 offer_answer_step() soa_static(0x615000090900, soa_generate_answer): storing local description
soa.c:1730 soa_activate() soa_activate(static::0x615000090900, (nil)) called
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x615000090900, [(nil)], [0x7f61ea812820], [0x7f61ea81281c]) called
tport.c:3286 tport_tsend() tport_tsend(0x6160000b1f80) tpn = UDP/172.18.0.2:5062
tport.c:4075 tport_resolve() tport_resolve addrinfo = 172.18.0.2:5062
tport.c:4709 tport_by_addrinfo() tport_by_addrinfo(0x6160000b1f80): not found by name UDP/172.18.0.2:5062
tport.c:3623 tport_vsend() tport_vsend(0x6160000b1f80): 918 bytes of 918 to udp/172.18.0.2:5062
tport.c:3521 tport_send_msg() tport_vsend returned 918
send 918 bytes to udp/[172.18.0.2]:5062 at 05:39:37.494902:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.18.0.2:5062;branch=z9hG4bK-19369-1-0
From: t_sipp <sip:t_sipp@172.18.0.2:5062>;tag=1
To: sut <sip:1212121212@172.18.0.2:5080>;tag=92N72Q25QpUrD
Call-ID: 1-19369@172.18.0.2
CSeq: 1 INVITE
Contact: <sip:1212121212@172.18.0.2:5080;transport=udp>
User-Agent: Unit Test
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Session-Expires: 600;refresher=uas
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 200
Remote-Party-ID: "1212121212" <sip:1212121212@172.18.0.2>;party=calling;privacy=off;screen=no

v=0
o=FS 1655933521 1655933522 IN IP4 172.18.0.2
s=FS
c=IN IP4 172.18.0.2
t=0 0
m=audio 29256 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

nta.c:6868 incoming_reply() nta: sent 200 OK for INVITE (1)
nta.c:1360 set_timeout() nta: timer shortened to 500 ms
nua_session.c:4140 signal_call_state_change() nua(0x6120001228c0): call state changed: received -> completed, sent answer
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x615000090900, [0x7f61ea8128e8], [0x7f61ea8128e0], [(nil)]) called
soa.c:616 soa_get_params() soa_get_params(static::0x615000090900, ...) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK
tport.c:2770 tport_wakeup_pri() tport_wakeup_pri(0x6160000b1f80): events IN
tport.c:2893 tport_recv_event() tport_recv_event(0x6160000b1f80)
tport.c:3234 tport_recv_iovec() tport_recv_iovec(0x6160000b1f80) msg 0x619000334080 from (udp/172.18.0.2:5080) has 357 bytes, veclen = 1
recv 357 bytes from udp/[172.18.0.2]:5062 at 05:39:37.495849:
------------------------------------------------------------------------
ACK sip:1212121212@172.18.0.2:5080 SIP/2.0
Via: SIP/2.0/UDP 172.18.0.2:5062;branch=z9hG4bK-19369-1-4
From: t_sipp <sip:t_sipp@172.18.0.2:5062>;tag=1
To: sut <sip:1212121212@172.18.0.2:5080>;tag=92N72Q25QpUrD
Call-ID: 1-19369@172.18.0.2
CSeq: 1 ACK
Contact: sip:t_sipp@172.18.0.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0


tport.c:3052 tport_deliver() tport_deliver(0x6160000b1f80): msg 0x619000334080 (357 bytes) from udp/172.18.0.2:5080/sip next=(nil)
nta.c:2951 agent_recv_request() nta: received ACK sip:1212121212@172.18.0.2:5080 SIP/2.0 (CSeq 1) (load: 0 rps)
nta.c:3090 agent_recv_request() nta: ACK (1) is going to INVITE (1)
nua_session.c:2567 process_ack_or_cancel() nua: process_ack_or_cancel: entering
soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x615000090900) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_ack 200 OK
nua_session.c:4140 signal_call_state_change() nua(0x6120001228c0): call state changed: completed -> ready
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_active 200 Call active
nua_dialog.c:564 nua_dialog_usage_set_refresh_range() nua(): refresh session after 296 seconds (in [295..305])
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1164 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:559 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:559 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1164 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0):    #1 0x56112c669ce9 in test_wait_for_chan_var test/sipp-based-tests.c:79
    #2 0x56112c661c59 in main test/sipp-based-tests.c:271
    #3 0x7f61f015a09a in __libc_start_main ../csu/libc-start.c:308
    #4 0x56112c669019 in _start (/drone/src/src/mod/endpoints/mod_sofia/test/.libs/sipp-based-tests+0x18019)

AddressSanitizer can not provide additional info.
SUMMARY: AddressSanitizer: SEGV (/usr/lib/x86_64-linux-gnu/libasan.so.5+0xb488a) 
==18018==ABORTING