Background mode - PID=[18677]
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:402 soa_set_params() soa_set_params(static::0x615000110380, ...) called
nua_stack.c:301 nua_stack_event() nua((nil)): event r_set_params 200 OK
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:402 soa_set_params() soa_set_params(static::0x615000100480, ...) called
nua_stack.c:301 nua_stack_event() nua((nil)): event r_set_params 200 OK
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:402 soa_set_params() soa_set_params(static::0x6150000f0580, ...) 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.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:389 nua_application_event() nua: nua_application_event: entering
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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:558 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
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.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: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((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
sipp 172.25.0.2:5080 -nr -p 5062 -m 1 -s 1212121212 -recv_timeout 10000 -timeout 10s -sf sipp-scenarios/uac_telephone_event.xml -bg 
2024-06-22 14:37:53.271834 96.67% [WARNING] switch_core.c:3492 Exit status (25344): sipp 172.25.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:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000371980 from (udp/172.25.0.2:5080) has 667 bytes, veclen = 1
recv 667 bytes from udp/[172.25.0.2]:5062 at 14:37:53.416061:
------------------------------------------------------------------------
INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-0
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>
Call-ID: 1-18670@172.25.0.2
CSeq: 1 INVITE
Contact: sip:t_sipp@172.25.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.25.0.2
s=-
c=IN IP4 172.25.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:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000371980 (667 bytes) from udp/172.25.0.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 1) (load: 1 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@172.25.0.2:5080 with contact
nta.c:3164 agent_recv_request() nta: INVITE (1) going to a default leg
nta.c:1369 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:938 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:278 soa_clone() soa_clone(static::0x615000100480, 0x606000066020, 0x6120001228c0) called
soa.c:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called
nta.c:4497 nta_leg_tcreate() nta_leg_tcreate(0x60c0000ac780)
soa.c:1301 soa_init_offer_answer() soa_init_offer_answer(static::0x615000100c00) called
soa.c:1170 soa_set_remote_sdp() soa_set_remote_sdp(static::0x615000100c00, (nil), 0x6170000c1b7c, 287) called
nua_dialog.c:336 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:4131 signal_call_state_change() nua(0x6120001228c0): call state changed: init -> received, received offer
soa.c:1096 soa_get_remote_sdp() soa_get_remote_sdp(static::0x615000100c00, [0x7f4e56dc5398], [0x7f4e56dc5390], [(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
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.412783 96.67% [NOTICE] switch_channel.c:1142 New Channel sofia/external/t_sipp@172.25.0.2:5062 [fe6a46eb-d726-496c-b2b8-38fd26fe723a]
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.412783 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_NEW (Cur 1 Tot 1)
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [INFO] sofia.c:10460 sofia/external/t_sipp@172.25.0.2:5062 receiving invite from 172.25.0.2:5062 version: 1.10.12-dev git e096ad2 2024-06-22 14:26:28Z 64bit call-id: 1-18670@172.25.0.2
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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.c:365 nua_handle_magic() nua: nua_handle_magic: entering
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [received][100]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 172.25.0.2
s=-
c=IN IP4 172.25.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

fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [DEBUG] sofia.c:7906 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_NEW -> CS_INIT
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:558 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:558 nua_signal() nua((nil)): sent signal r_unref
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:600 (sofia/external/t_sipp@172.25.0.2:5062) State NEW
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_INIT (Cur 1 Tot 1)
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@172.25.0.2:5062) State INIT
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] mod_sofia.c:97 sofia/external/t_sipp@172.25.0.2:5062 SOFIA INIT
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:40 sofia/external/t_sipp@172.25.0.2:5062 Standard INIT
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:48 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_INIT -> CS_ROUTING
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@172.25.0.2:5062) State INIT going to sleep
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_ROUTING (Cur 1 Tot 1)
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_channel.c:2399 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change DOWN -> RINGING
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@172.25.0.2:5062) State ROUTING
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:601 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:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 259 bytes of 259 to udp/172.25.0.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 259
send 259 bytes to udp/[172.25.0.2]:5062 at 14:37:53.489743:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-0
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>
Call-ID: 1-18670@172.25.0.2
CSeq: 1 INVITE
User-Agent: Unit Test
Content-Length: 0


nta.c:6879 incoming_reply() nta: sent 100 Trying for INVITE (1)
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_respond
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [DEBUG] mod_sofia.c:158 sofia/external/t_sipp@172.25.0.2:5062 SOFIA ROUTING
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [DEBUG] switch_core_state_machine.c:230 sofia/external/t_sipp@172.25.0.2:5062 Standard ROUTING
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [INFO] mod_dialplan_xml.c:639 Processing t_sipp <t_sipp>->1212121212 in context default
fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 parsing [default->unit_test_sipp-uac-digest-leak] continue=false
fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Regex (FAIL) [unit_test_sipp-uac-digest-leak] destination_number(1212121212) =~ /^\+15553334444$/ break=on-false
fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 parsing [default->sipp_telephone_check] continue=false
fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Regex (PASS) [sipp_telephone_check] destination_number(1212121212) =~ /^1212121212$/ break=on-false
fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Action answer() 
fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Action set(park_after_bridge=true) 
fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Action park() 
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:281 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_ROUTING -> CS_EXECUTE
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@172.25.0.2:5062) State ROUTING going to sleep
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_EXECUTE (Cur 1 Tot 1)
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:647 (sofia/external/t_sipp@172.25.0.2:5062) State EXECUTE
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] mod_sofia.c:213 sofia/external/t_sipp@172.25.0.2:5062 SOFIA EXECUTE
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:323 sofia/external/t_sipp@172.25.0.2:5062 Standard EXECUTE
fe6a46eb-d726-496c-b2b8-38fd26fe723a EXECUTE [depth=0] sofia/external/t_sipp@172.25.0.2:5062 answer()
nta.c:1317 agent_timer() nta: timer set next to 4229 ms
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:3731 Set Codec sofia/external/t_sipp@172.25.0.2:5062 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_codec.c:111 sofia/external/t_sipp@172.25.0.2:5062 Original read codec set to PCMU:0
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.779695 96.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.779695 96.67% [DEBUG] switch_core_media.c:5856 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf send payload to 101 recv payload to 101
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.799779 96.67% [DEBUG] switch_core_media.c:8660 AUDIO RTP [sofia/external/t_sipp@172.25.0.2:5062] 172.25.0.2 port 24146 -> 172.25.0.2 port 6000 codec: 0 ms: 20
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.799779 96.67% [DEBUG] switch_rtp.c:4563 Starting timer [soft] 160 bytes per 20ms
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [DEBUG] switch_core_media.c:8973 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf send payload to 101
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [DEBUG] switch_core_media.c:8980 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf receive payload to 101
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [DEBUG] switch_core_media.c:9003 sofia/external/t_sipp@172.25.0.2:5062 Set rtp dtmf delay to 40
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [NOTICE] sofia_media.c:90 Pre-Answer sofia/external/t_sipp@172.25.0.2:5062!
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.111831 96.67% [DEBUG] switch_channel.c:3585 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change RINGING -> EARLY
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.171834 93.33% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/t_sipp@172.25.0.2:5062.
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.171834 93.33% [DEBUG] mod_sofia.c:914 Local SDP sofia/external/t_sipp@172.25.0.2:5062:
v=0
o=FS 1719042928 1719042929 IN IP4 172.25.0.2
s=FS
c=IN IP4 172.25.0.2
t=0 0
m=audio 24146 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:601 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:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called
soa.c:1051 soa_set_user_sdp() soa_set_user_sdp(static::0x615000100c00, (nil), 0x6170001107ce, -1) called
soa.c:889 soa_set_capability_sdp() soa_set_capability_sdp(static::0x615000100c00, (nil), 0x6170001107ce, -1) called
nua_session.c:2317 nua_invite_server_respond() nua: nua_invite_server_respond: entering
soa.c:1514 soa_generate_answer() soa_generate_answer(static::0x615000100c00) called
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x615000100c00, soa_generate_answer): called
soa_static.c:1188 offer_answer_step() soa_static(0x615000100c00, soa_generate_answer): generating local description
soa_static.c:1229 offer_answer_step() soa_static(0x615000100c00, soa_generate_answer): upgrade with remote description
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f4e56dc5730, 0x6180000f0880, ""): called
soa_static.c:1445 offer_answer_step() soa_static(0x615000100c00, soa_generate_answer): storing local description
soa.c:1729 soa_activate() soa_activate(static::0x615000100c00, (nil)) called
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [(nil)], [0x7f4e56dc5820], [0x7f4e56dc581c]) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 918 bytes of 918 to udp/172.25.0.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 918
send 918 bytes to udp/[172.25.0.2]:5062 at 14:37:54.234708:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-0
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS
Call-ID: 1-18670@172.25.0.2
CSeq: 1 INVITE
Contact: <sip:1212121212@172.25.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.25.0.2>;party=calling;privacy=off;screen=no

v=0
o=FS 1719042928 1719042929 IN IP4 172.25.0.2
s=FS
c=IN IP4 172.25.0.2
t=0 0
m=audio 24146 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:6879 incoming_reply() nta: sent 200 OK for INVITE (1)
nta.c:1369 set_timeout() nta: timer shortened to 500 ms
nua_session.c:4131 signal_call_state_change() nua(0x6120001228c0): call state changed: received -> completed, sent answer
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [0x7f4e56dc58e8], [0x7f4e56dc58e0], [(nil)]) called
soa.c:615 soa_get_params() soa_get_params(static::0x615000100c00, ...) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000374180 from (udp/172.25.0.2:5080) has 357 bytes, veclen = 1
recv 357 bytes from udp/[172.25.0.2]:5062 at 14:37:54.240021:
------------------------------------------------------------------------
ACK sip:1212121212@172.25.0.2:5080 SIP/2.0
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-4
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS
Call-ID: 1-18670@172.25.0.2
CSeq: 1 ACK
Contact: sip:t_sipp@172.25.0.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0


tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000374180 (357 bytes) from udp/172.25.0.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received ACK sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 1) (load: 1 rps)
nta.c:3097 agent_recv_request() nta: ACK (1) is going to INVITE (1)
nua_session.c:2566 process_ack_or_cancel() nua: process_ack_or_cancel: entering
soa.c:1213 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x615000100c00) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_ack 200 OK
nua_session.c:4131 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:563 nua_dialog_usage_set_refresh_range() nua(): refresh session after 302 seconds (in [295..305])
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): 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(0x6120001228c0): 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(0x6120001228c0): 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(0x6120001228c0): sent signal r_respond
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.231905 93.33% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [completed][200]
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.312377 93.33% [NOTICE] mod_dptools.c:1406 Channel [sofia/external/t_sipp@172.25.0.2:5062] has been answered
2024-06-22 14:37:54.351834 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:54.471938 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=298 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.511831 93.33% [DEBUG] switch_channel.c:3912 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change EARLY -> ACTIVE
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.611833 93.33% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [ready][200]
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
2024-06-22 14:37:54.659669 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=297 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
fe6a46eb-d726-496c-b2b8-38fd26fe723a EXECUTE [depth=0] sofia/external/t_sipp@172.25.0.2:5062 set(park_after_bridge=true)
nta.c:1317 agent_timer() nta: timer set next to 3111 ms
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.815668 93.33% [DEBUG] mod_dptools.c:1671 SET sofia/external/t_sipp@172.25.0.2:5062 [park_after_bridge]=[true]
fe6a46eb-d726-496c-b2b8-38fd26fe723a EXECUTE [depth=0] sofia/external/t_sipp@172.25.0.2:5062 park()
2024-06-22 14:37:54.898887 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=296 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:55.072059 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=295 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:55.177017 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=294 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:55.311831 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=293 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:55.435663 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=292 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:55.551833 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=291 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:55.679676 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=290 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000374680 from (udp/172.25.0.2:5080) has 349 bytes, veclen = 1
recv 349 bytes from udp/[172.25.0.2]:5062 at 14:37:55.743964:
------------------------------------------------------------------------
INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-6
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>
Call-ID: 1-18670@172.25.0.2
CSeq: 2 INVITE
Contact: sip:t_sipp@172.25.0.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Length:     0


tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000374680 (349 bytes) from udp/172.25.0.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 2) (load: 0 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@172.25.0.2:5080 with contact
nta.c:3139 agent_recv_request() nta: INVITE (2) going to existing leg
nta.c:1369 set_timeout() nta: timer shortened to 200 ms
nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
soa.c:1301 soa_init_offer_answer() soa_init_offer_answer(static::0x615000100c00) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_invite 100 Trying
nua_session.c:4138 signal_call_state_change() nua(0x6120001228c0): ready call updated: received
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:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_respond
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:601 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:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 277 bytes of 277 to udp/172.25.0.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 277
send 277 bytes to udp/[172.25.0.2]:5062 at 14:37:55.750015:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-6
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS
Call-ID: 1-18670@172.25.0.2
CSeq: 2 INVITE
User-Agent: Unit Test
Content-Length: 0


nta.c:6879 incoming_reply() nta: sent 100 Trying for INVITE (2)
2024-06-22 14:37:55.871832 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=289 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
nta.c:1317 agent_timer() nta: timer set next to 1901 ms
2024-06-22 14:37:55.991837 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=288 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:56.133397 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=287 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.195658 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [received][100]
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:601 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:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called
soa.c:1051 soa_set_user_sdp() soa_set_user_sdp(static::0x615000100c00, (nil), 0x6150001a02ff, -1) called
nua_session.c:2317 nua_invite_server_respond() nua: nua_invite_server_respond: entering
soa.c:1425 soa_generate_offer() soa_generate_offer(static::0x615000100c00, 0) called
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x615000100c00, soa_generate_offer): called
soa_static.c:1216 offer_answer_step() soa_static(0x615000100c00, soa_generate_offer): upgrade with local description
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f4e56dc5720, (nil), ""): called
soa_static.c:1445 offer_answer_step() soa_static(0x615000100c00, soa_generate_offer): storing local description
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [(nil)], [0x7f4e56dc5820], [0x7f4e56dc581c]) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 761 bytes of 761 to udp/172.25.0.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 761
send 761 bytes to udp/[172.25.0.2]:5062 at 14:37:56.199048:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-6
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS
Call-ID: 1-18670@172.25.0.2
CSeq: 2 INVITE
Contact: <sip:1212121212@172.25.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
Session-Expires: 600;refresher=uas
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 183

v=0
o=FS 1719042928 1719042930 IN IP4 172.25.0.2
s=FS
c=IN IP4 172.25.0.2
t=0 0
m=audio 24146 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20

nta.c:6879 incoming_reply() nta: sent 200 OK for INVITE (2)
nta.c:1369 set_timeout() nta: timer shortened to 500 ms
nua_session.c:4138 signal_call_state_change() nua(0x6120001228c0): ready call updated: completed sent offer
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [0x7f4e56dc58e8], [0x7f4e56dc58e0], [(nil)]) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): 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(0x6120001228c0): sent signal r_respond
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000376980 from (udp/172.25.0.2:5080) has 626 bytes, veclen = 1
recv 626 bytes from udp/[172.25.0.2]:5062 at 14:37:56.207989:
------------------------------------------------------------------------
ACK sip:1212121212@172.25.0.2:5080 SIP/2.0
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-9
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS
Call-ID: 1-18670@172.25.0.2
CSeq: 2 ACK
Contact: sip:t_sipp@172.25.0.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Type: application/sdp
Content-Length:   234

v=0
o=user1 53655765 2353687637 IN IP4 172.25.0.2
s=
c=IN IP4 172.25.0.2
t=0 0
m=audio 6000 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=ptime:20

tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000376980 (626 bytes) from udp/172.25.0.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received ACK sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 2) (load: 1 rps)
nta.c:3097 agent_recv_request() nta: ACK (2) is going to INVITE (2)
nua_session.c:2566 process_ack_or_cancel() nua: process_ack_or_cancel: entering
soa.c:1170 soa_set_remote_sdp() soa_set_remote_sdp(static::0x615000100c00, (nil), 0x616000108208, 234) called
soa.c:1594 soa_process_answer() soa_process_answer(static::0x615000100c00) called
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x615000100c00, soa_process_answer): called
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x6170000c4400, 0x6170000c5580, ""): called
soa_static.c:1303 offer_answer_step() soa_static(0x615000100c00, soa_process_answer): upgrade codecs with remote description
soa.c:1729 soa_activate() soa_activate(static::0x615000100c00, (nil)) called
soa.c:1213 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x615000100c00) called
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_ack 200 OK
nua_session.c:4138 signal_call_state_change() nua(0x6120001228c0): ready call updated: ready received answer
soa.c:1096 soa_get_remote_sdp() soa_get_remote_sdp(static::0x615000100c00, [0x7f4e56dc54c8], [0x7f4e56dc54c0], [(nil)]) called
soa.c:615 soa_get_params() soa_get_params(static::0x615000100c00, ...) called
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:563 nua_dialog_usage_set_refresh_range() nua(): refresh session after 297 seconds (in [295..305])
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): 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(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua.c:1170 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:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN
tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80)
tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000376e80 from (udp/172.25.0.2:5080) has 358 bytes, veclen = 1
recv 358 bytes from udp/[172.25.0.2]:5062 at 14:37:56.212466:
------------------------------------------------------------------------
BYE sip:1212121212@172.25.0.2:5080 SIP/2.0
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-10
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS
Call-ID: 1-18670@172.25.0.2
CSeq: 3 BYE
Contact: sip:t_sipp@172.25.0.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0


tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000376e80 (358 bytes) from udp/172.25.0.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received BYE sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 3) (load: 1 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@172.25.0.2:5080 with contact
nta.c:3139 agent_recv_request() nta: BYE (3) going to existing leg
nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_bye 100 Trying
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): 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.c:365 nua_handle_magic() nua: nua_handle_magic: entering
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.211927 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [completed][200]
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.235654 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [ready][200]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.235654 86.67% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 172.25.0.2
s=
c=IN IP4 172.25.0.2
t=0 0
m=audio 6000 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5856 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf send payload to 101 recv payload to 101
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/t_sipp@172.25.0.2:5062.
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 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:558 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:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
2024-06-22 14:37:56.271870 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=286 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:56.431832 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=285 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:56.571833 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=284 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
2024-06-22 14:37:56.671835 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=283 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
nta.c:1317 agent_timer() nta: timer set next to 1147 ms
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.695697 86.67% [NOTICE] sofia.c:1065 Hangup sofia/external/t_sipp@172.25.0.2:5062 [CS_EXECUTE] [NORMAL_CLEARING]
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:601 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:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 395 bytes of 395 to udp/172.25.0.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 395
send 395 bytes to udp/[172.25.0.2]:5062 at 14:37:56.783107:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-10
From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1
To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS
Call-ID: 1-18670@172.25.0.2
CSeq: 3 BYE
User-Agent: Unit Test
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Content-Length: 0


nta.c:6879 incoming_reply() nta: sent 200 OK for BYE (3)
nua_dialog.c:395 nua_dialog_usage_remove_at() nua(0x6120001228c0): removing session usage
nua_session.c:4131 signal_call_state_change() nua(0x6120001228c0): call state changed: ready -> terminated
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 Session Terminated
nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_terminated 200 Session Terminated
soa.c:355 soa_destroy() soa_destroy(static::0x615000100c00) called
nta.c:4550 nta_leg_destroy() nta_leg_destroy(0x60c0000ac780)
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): 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(0x6120001228c0): 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(0x6120001228c0): sent signal r_respond
nua.c:341 nua_handle_bind() nua: nua_handle_bind: entering
nua.c:920 nua_handle_destroy() nua: nua_handle_destroy: entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_destroy
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering
nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent 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(0x6120001228c0): recv signal r_destroy
nta.c:4550 nta_leg_destroy() nta_leg_destroy((nil))
nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
2024-06-22 14:37:56.852290 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=282 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch]
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.852290 86.67% [DEBUG] switch_core_session.c:2979 sofia/external/t_sipp@172.25.0.2:5062 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.891834 86.67% [DEBUG] switch_core_state_machine.c:647 (sofia/external/t_sipp@172.25.0.2:5062) State EXECUTE going to sleep
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.891834 86.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_HANGUP (Cur 1 Tot 1)
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:844 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change ACTIVE -> HANGUP
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:846 (sofia/external/t_sipp@172.25.0.2:5062) State HANGUP
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] mod_sofia.c:469 Channel sofia/external/t_sipp@172.25.0.2:5062 hanging up, cause: NORMAL_CLEARING
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:59 sofia/external/t_sipp@172.25.0.2:5062 Standard HANGUP, cause: NORMAL_CLEARING
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:846 (sofia/external/t_sipp@172.25.0.2:5062) State HANGUP going to sleep
fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:616 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_HANGUP -> CS_REPORTING
2024-06-22 14:37:56.979654 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=281 [select * from channels where hostname='edAddressSanitizer:DEADLYSIGNAL
=================================================================
==17518==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000030 (pc 0x7f4e5cbae3f8 bp 0x7fff08438be0 sp 0x7fff08438be0 T0)
==17518==The signal is caused by a READ memory access.
==17518==Hint: address points to the zero page.
    #0 0x7f4e5cbae3f8 in switch_core_session_get_channel src/switch_core_session.c:1433
    #1 0x561f0422efb7 in main test/sipp-based-tests.c:271
    #2 0x7f4e5c84ed09 in __libc_start_main ../csu/libc-start.c:308
    #3 0x561f042372f9 in _start (/drone/src/src/mod/endpoints/mod_sofia/test/.libs/sipp-based-tests+0x182f9)

AddressSanitizer can not provide additional info.
SUMMARY: AddressSanitizer: SEGV src/switch_core_session.c:1433 in switch_core_session_get_channel
==17518==ABORTING